2025-12-01 00:06:48.863736 | Job console starting...
2025-12-01 00:06:48.875740 | Updating repositories
2025-12-01 00:06:48.989884 | Preparing job workspace
2025-12-01 00:06:52.673736 | Running Ansible setup...
2025-12-01 00:06:57.150482 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-12-01 00:06:57.850155 | 
2025-12-01 00:06:57.850345 | PLAY [localhost]
2025-12-01 00:06:57.859551 | 
2025-12-01 00:06:57.859634 | TASK [Gathering Facts]
2025-12-01 00:06:58.884602 | localhost | ok
2025-12-01 00:06:58.898533 | 
2025-12-01 00:06:58.898631 | TASK [Setup log path fact]
2025-12-01 00:06:58.917223 | localhost | ok
2025-12-01 00:06:58.929381 | 
2025-12-01 00:06:58.929445 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-01 00:06:58.957044 | localhost | ok
2025-12-01 00:06:58.964751 | 
2025-12-01 00:06:58.964817 | TASK [emit-job-header : Print job information]
2025-12-01 00:06:59.003025 | # Job Information
2025-12-01 00:06:59.003142 | Ansible Version: 2.15.12
2025-12-01 00:06:59.003172 | Job: ansible-test-sanity-docker-milestone
2025-12-01 00:06:59.003193 | Pipeline: periodic
2025-12-01 00:06:59.003211 | Executor: ze02.softwarefactory-project.io
2025-12-01 00:06:59.003228 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-12-01 00:06:59.003248 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/b86/ansible/b8624190bd8e443f9005d5dd76b11a5b/
2025-12-01 00:06:59.003267 | Event ID: 7f239586727e4cf3b26e006c8a5b9dc9
2025-12-01 00:06:59.006854 | 
2025-12-01 00:06:59.006912 | LOOP [emit-job-header : Print node information]
2025-12-01 00:06:59.114718 | localhost | ok:
2025-12-01 00:06:59.115048 | localhost | # Node Information
2025-12-01 00:06:59.115083 | localhost | Inventory Hostname: controller
2025-12-01 00:06:59.115103 | localhost | Hostname: np0005540469
2025-12-01 00:06:59.115122 | localhost | Username: zuul
2025-12-01 00:06:59.115148 | localhost | Distro: Fedora 37
2025-12-01 00:06:59.115167 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2025-12-01 00:06:59.115186 | localhost | Region: ca-ymq-1
2025-12-01 00:06:59.115202 | localhost | Label: ansible-fedora-37-1vcpu
2025-12-01 00:06:59.115219 | localhost | Product Name: OpenStack Nova
2025-12-01 00:06:59.115235 | localhost | Interface IP: 162.253.55.58
2025-12-01 00:06:59.134064 | 
2025-12-01 00:06:59.134252 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-01 00:06:59.612180 | localhost -> localhost | changed
2025-12-01 00:06:59.620550 | 
2025-12-01 00:06:59.620731 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-01 00:07:00.492823 | localhost -> localhost | changed
2025-12-01 00:07:00.535209 | 
2025-12-01 00:07:00.535303 | PLAY [all:!appliance*]
2025-12-01 00:07:00.551763 | 
2025-12-01 00:07:00.551880 | TASK [include_role : start-zuul-console]
2025-12-01 00:07:00.570533 | controller | ok
2025-12-01 00:07:00.584399 | 
2025-12-01 00:07:00.584511 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-01 00:07:01.216323 | controller | ok
2025-12-01 00:07:01.235090 | 
2025-12-01 00:07:01.235261 | TASK [use-our-mirror : Retrieve the IP address]
2025-12-01 00:07:02.039084 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-12-01 00:07:02.054922 | 
2025-12-01 00:07:02.055065 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-12-01 00:07:02.599406 | controller | skipping: Conditional result was False
2025-12-01 00:07:02.616076 | 
2025-12-01 00:07:02.618241 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-12-01 00:07:02.648040 | controller | skipping: Conditional result was False
2025-12-01 00:07:02.656432 | 
2025-12-01 00:07:02.656515 | TASK [use-our-mirror : Create the podman configuration directory]
2025-12-01 00:07:02.680312 | controller | skipping: Conditional result was False
2025-12-01 00:07:02.688164 | 
2025-12-01 00:07:02.688262 | TASK [use-our-mirror : Copy the podman configuration]
2025-12-01 00:07:02.724031 | controller | skipping: Conditional result was False
2025-12-01 00:07:02.732965 | 
2025-12-01 00:07:02.733050 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-12-01 00:07:02.766074 | controller | skipping: Conditional result was False
2025-12-01 00:07:02.775182 | 
2025-12-01 00:07:02.775287 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-12-01 00:07:02.791176 | controller | skipping: Conditional result was False
2025-12-01 00:07:02.810102 | 
2025-12-01 00:07:02.810237 | TASK [Disable Fedora Modular]
2025-12-01 00:07:03.083346 | controller | changed
2025-12-01 00:07:03.099138 | 
2025-12-01 00:07:03.099332 | TASK [Enable EPEL]
2025-12-01 00:07:03.125561 | controller | skipping: Conditional result was False
2025-12-01 00:07:03.136380 | 
2025-12-01 00:07:03.136548 | TASK [Register the RHEL node]
2025-12-01 00:07:03.690328 | 
2025-12-01 00:07:03.690496 | TASK [Show the subscription-manager status]
2025-12-01 00:07:04.258007 | controller | skipping: Conditional result was False
2025-12-01 00:07:04.267346 | 
2025-12-01 00:07:04.267434 | TASK [Enable EPEL on RHEL]
2025-12-01 00:07:04.812476 | controller | skipping: Conditional result was False
2025-12-01 00:07:04.826993 | 
2025-12-01 00:07:04.827139 | TASK [Install git and tox]
2025-12-01 00:08:38.579025 | controller | changed
2025-12-01 00:08:38.592456 | 
2025-12-01 00:08:38.592588 | TASK [include_role : prepare-workspace]
2025-12-01 00:08:38.632059 | controller | ok
2025-12-01 00:08:38.674310 | 
2025-12-01 00:08:38.674451 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-01 00:08:38.935456 | controller | ok
2025-12-01 00:08:38.950643 | 
2025-12-01 00:08:38.950859 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-01 00:14:09.613586 | controller | Output suppressed because no_log was given
2025-12-01 00:14:09.623660 | 
2025-12-01 00:14:09.623779 | TASK [include_role : prepare-workspace-openshift]
2025-12-01 00:14:09.648607 | controller | skipping: Conditional result was False
2025-12-01 00:14:09.670794 | 
2025-12-01 00:14:09.670852 | PLAY [all:!appliance]
2025-12-01 00:14:09.690629 | 
2025-12-01 00:14:09.690795 | TASK [Run add-build-sshkey role (RSA)]
2025-12-01 00:14:09.722460 | controller | ok
2025-12-01 00:14:09.737012 | 
2025-12-01 00:14:09.737075 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-01 00:14:09.984058 | controller -> localhost | ok
2025-12-01 00:14:09.990785 | 
2025-12-01 00:14:09.990870 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-01 00:14:10.021495 | controller | ok
2025-12-01 00:14:10.036858 | controller | included: /var/lib/zuul/builds/b8624190bd8e443f9005d5dd76b11a5b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-01 00:14:10.042707 | 
2025-12-01 00:14:10.042770 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-01 00:14:10.557222 | controller -> localhost | Generating public/private rsa key pair.
2025-12-01 00:14:10.557474 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/b8624190bd8e443f9005d5dd76b11a5b/work/b8624190bd8e443f9005d5dd76b11a5b_id_rsa.
2025-12-01 00:14:10.557504 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/b8624190bd8e443f9005d5dd76b11a5b/work/b8624190bd8e443f9005d5dd76b11a5b_id_rsa.pub.
2025-12-01 00:14:10.557525 | controller -> localhost | The key fingerprint is:
2025-12-01 00:14:10.557544 | controller -> localhost | SHA256:+z4nSjnQ+2hZHvMXo0qnG2U/Oqryex38kjKDBR1jH4U zuul-build-sshkey
2025-12-01 00:14:10.557564 | controller -> localhost | The key's randomart image is:
2025-12-01 00:14:10.557582 | controller -> localhost | +---[RSA 2048]----+
2025-12-01 00:14:10.557601 | controller -> localhost | |             o.  |
2025-12-01 00:14:10.557619 | controller -> localhost | |          + E    |
2025-12-01 00:14:10.557636 | controller -> localhost | |         o + .   |
2025-12-01 00:14:10.557653 | controller -> localhost | |       .. . .    |
2025-12-01 00:14:10.557690 | controller -> localhost | |      . S. .o    |
2025-12-01 00:14:10.557718 | controller -> localhost | |       . +=oo.o  |
2025-12-01 00:14:10.557741 | controller -> localhost | |        **o=.=oo |
2025-12-01 00:14:10.557760 | controller -> localhost | |      ..+*O+O.o. |
2025-12-01 00:14:10.557778 | controller -> localhost | |       +**O@.+   |
2025-12-01 00:14:10.557798 | controller -> localhost | +----[SHA256]-----+
2025-12-01 00:14:10.557858 | controller -> localhost | ok: Runtime: 0:00:00.064130
2025-12-01 00:14:10.564933 | 
2025-12-01 00:14:10.564994 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-01 00:14:10.606074 | controller | ok
2025-12-01 00:14:10.616081 | controller | included: /var/lib/zuul/builds/b8624190bd8e443f9005d5dd76b11a5b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-01 00:14:10.625778 | 
2025-12-01 00:14:10.625840 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-01 00:14:10.650794 | controller | skipping: Conditional result was False
2025-12-01 00:14:10.658155 | 
2025-12-01 00:14:10.658234 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-01 00:14:11.315797 | controller | changed
2025-12-01 00:14:11.329212 | 
2025-12-01 00:14:11.329350 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-01 00:14:11.565288 | controller | ok
2025-12-01 00:14:11.581605 | 
2025-12-01 00:14:11.581951 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-01 00:14:12.281155 | controller | changed
2025-12-01 00:14:12.297244 | 
2025-12-01 00:14:12.297427 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-01 00:14:12.960091 | controller | changed
2025-12-01 00:14:12.973288 | 
2025-12-01 00:14:12.973420 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-01 00:14:13.011498 | controller | skipping: Conditional result was False
2025-12-01 00:14:13.024958 | 
2025-12-01 00:14:13.025123 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-01 00:14:13.468865 | controller -> localhost | changed
2025-12-01 00:14:13.504139 | 
2025-12-01 00:14:13.504279 | TASK [add-build-sshkey : Add back temp key]
2025-12-01 00:14:13.833168 | controller -> localhost | Identity added: /var/lib/zuul/builds/b8624190bd8e443f9005d5dd76b11a5b/work/b8624190bd8e443f9005d5dd76b11a5b_id_rsa (zuul-build-sshkey)
2025-12-01 00:14:13.833549 | controller -> localhost | ok: Runtime: 0:00:00.014406
2025-12-01 00:14:13.847227 | 
2025-12-01 00:14:13.847359 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-01 00:14:14.225494 | controller | ok
2025-12-01 00:14:14.237269 | 
2025-12-01 00:14:14.237400 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-01 00:14:14.274949 | controller | skipping: Conditional result was False
2025-12-01 00:14:14.298984 | 
2025-12-01 00:14:14.299114 | TASK [Run add-build-sshkey role (ECDSA)]
2025-12-01 00:14:14.332941 | controller | ok
2025-12-01 00:14:14.355261 | 
2025-12-01 00:14:14.355344 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-01 00:14:14.603637 | controller -> localhost | ok
2025-12-01 00:14:14.610335 | 
2025-12-01 00:14:14.610398 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-01 00:14:14.642649 | controller | ok
2025-12-01 00:14:14.657212 | controller | included: /var/lib/zuul/builds/b8624190bd8e443f9005d5dd76b11a5b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-01 00:14:14.663756 | 
2025-12-01 00:14:14.663816 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-01 00:14:14.961388 | controller -> localhost | Generating public/private ecdsa key pair.
2025-12-01 00:14:14.961574 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/b8624190bd8e443f9005d5dd76b11a5b/work/b8624190bd8e443f9005d5dd76b11a5b_id_ecdsa.
2025-12-01 00:14:14.961600 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/b8624190bd8e443f9005d5dd76b11a5b/work/b8624190bd8e443f9005d5dd76b11a5b_id_ecdsa.pub.
2025-12-01 00:14:14.961629 | controller -> localhost | The key fingerprint is:
2025-12-01 00:14:14.961647 | controller -> localhost | SHA256:ZXqFevq31aGRsQrr5/UNdULW11NcnbEJgsFismo9IW4 zuul-build-sshkey
2025-12-01 00:14:14.961683 | controller -> localhost | The key's randomart image is:
2025-12-01 00:14:14.961706 | controller -> localhost | +---[ECDSA 521]---+
2025-12-01 00:14:14.961724 | controller -> localhost | |        ..o. . oB|
2025-12-01 00:14:14.961741 | controller -> localhost | |     . o o .. .oB|
2025-12-01 00:14:14.961758 | controller -> localhost | |      + . + ..o++|
2025-12-01 00:14:14.961775 | controller -> localhost | |   . o   = . o+ o|
2025-12-01 00:14:14.961791 | controller -> localhost | |  . + . S.o  +..o|
2025-12-01 00:14:14.961807 | controller -> localhost | |   E o   +o . o+o|
2025-12-01 00:14:14.961824 | controller -> localhost | |  o   . .. . oo .|
2025-12-01 00:14:14.961840 | controller -> localhost | |        .. .o....|
2025-12-01 00:14:14.961856 | controller -> localhost | |         .+o.. ..|
2025-12-01 00:14:14.961872 | controller -> localhost | +----[SHA256]-----+
2025-12-01 00:14:14.961919 | controller -> localhost | ok: Runtime: 0:00:00.014802
2025-12-01 00:14:14.969394 | 
2025-12-01 00:14:14.969477 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-01 00:14:15.001302 | controller | ok
2025-12-01 00:14:15.011696 | controller | included: /var/lib/zuul/builds/b8624190bd8e443f9005d5dd76b11a5b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-01 00:14:15.024236 | 
2025-12-01 00:14:15.024318 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-01 00:14:15.049196 | controller | skipping: Conditional result was False
2025-12-01 00:14:15.055215 | 
2025-12-01 00:14:15.055275 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-01 00:14:15.365462 | controller | changed
2025-12-01 00:14:15.380857 | 
2025-12-01 00:14:15.380987 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-01 00:14:15.627152 | controller | ok
2025-12-01 00:14:15.640857 | 
2025-12-01 00:14:15.641028 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-01 00:14:16.271102 | controller | changed
2025-12-01 00:14:16.283741 | 
2025-12-01 00:14:16.284441 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-01 00:14:16.951949 | controller | changed
2025-12-01 00:14:16.959422 | 
2025-12-01 00:14:16.959503 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-01 00:14:16.985123 | controller | skipping: Conditional result was False
2025-12-01 00:14:16.993530 | 
2025-12-01 00:14:16.993614 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-01 00:14:17.272244 | controller -> localhost | changed
2025-12-01 00:14:17.283529 | 
2025-12-01 00:14:17.283630 | TASK [add-build-sshkey : Add back temp key]
2025-12-01 00:14:17.546344 | controller -> localhost | Identity added: /var/lib/zuul/builds/b8624190bd8e443f9005d5dd76b11a5b/work/b8624190bd8e443f9005d5dd76b11a5b_id_ecdsa (zuul-build-sshkey)
2025-12-01 00:14:17.546739 | controller -> localhost | ok: Runtime: 0:00:00.014196
2025-12-01 00:14:17.561909 | 
2025-12-01 00:14:17.561999 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-01 00:14:17.792533 | controller | ok
2025-12-01 00:14:17.804440 | 
2025-12-01 00:14:17.804577 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-01 00:14:17.831492 | controller | skipping: Conditional result was False
2025-12-01 00:14:17.857478 | 
2025-12-01 00:14:17.857615 | TASK [include_role : remove-zuul-sshkey]
2025-12-01 00:14:17.874727 | controller | skipping: Conditional result was False
2025-12-01 00:14:17.885011 | 
2025-12-01 00:14:17.885109 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-01 00:14:18.112021 | controller | ok: "logs"
2025-12-01 00:14:18.112478 | controller | ok: All items complete
2025-12-01 00:14:18.112537 | 
2025-12-01 00:14:18.297026 | controller | ok: "artifacts"
2025-12-01 00:14:18.483872 | controller | ok: "docs"
2025-12-01 00:14:18.505395 | 
2025-12-01 00:14:18.505557 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-01 00:14:18.744217 | controller | changed: "logs"
2025-12-01 00:14:18.925925 | controller | changed: "artifacts"
2025-12-01 00:14:19.127372 | controller | changed: "docs"
2025-12-01 00:14:19.165132 | 
2025-12-01 00:14:19.165279 | PLAY RECAP
2025-12-01 00:14:19.165331 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-12-01 00:14:19.165395 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-01 00:14:19.165414 | 
2025-12-01 00:14:19.283596 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-12-01 00:14:19.284745 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-01 00:14:19.844178 | 
2025-12-01 00:14:19.844304 | PLAY [all]
2025-12-01 00:14:19.868033 | 
2025-12-01 00:14:19.868138 | TASK [Install binary dependencies]
2025-12-01 00:14:19.928203 | controller | ok
2025-12-01 00:14:19.947547 | 
2025-12-01 00:14:19.947653 | TASK [bindep : Include find tasks]
2025-12-01 00:14:19.977152 | controller | ok
2025-12-01 00:14:19.985048 | controller | included: /var/lib/zuul/builds/b8624190bd8e443f9005d5dd76b11a5b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-12-01 00:14:19.991773 | 
2025-12-01 00:14:19.991861 | TASK [bindep : Look for bindep.txt]
2025-12-01 00:14:20.538599 | controller | ok
2025-12-01 00:14:20.544956 | 
2025-12-01 00:14:20.545032 | TASK [bindep : Define bindep_file fact]
2025-12-01 00:14:20.569514 | controller | skipping: Conditional result was False
2025-12-01 00:14:20.576596 | 
2025-12-01 00:14:20.576681 | TASK [bindep : Look for other-requirements.txt]
2025-12-01 00:14:20.802003 | controller | ok
2025-12-01 00:14:20.814500 | 
2025-12-01 00:14:20.814932 | TASK [bindep : Define bindep_file fact]
2025-12-01 00:14:20.854174 | controller | skipping: Conditional result was False
2025-12-01 00:14:20.870894 | 
2025-12-01 00:14:20.871091 | TASK [bindep : Look for bindep fallback file]
2025-12-01 00:14:20.912027 | controller | skipping: Conditional result was False
2025-12-01 00:14:20.928033 | 
2025-12-01 00:14:20.928180 | TASK [bindep : Define bindep_file fact]
2025-12-01 00:14:20.965541 | controller | skipping: Conditional result was False
2025-12-01 00:14:20.974595 | 
2025-12-01 00:14:20.974708 | TASK [bindep : Include bindep tasks]
2025-12-01 00:14:20.998755 | controller | skipping: Conditional result was False
2025-12-01 00:14:21.007400 | 
2025-12-01 00:14:21.007487 | TASK [bindep : Include install tasks]
2025-12-01 00:14:21.032046 | controller | skipping: Conditional result was False
2025-12-01 00:14:21.041167 | 
2025-12-01 00:14:21.041253 | LOOP [bindep : Include package tasks]
2025-12-01 00:14:21.103069 | 
2025-12-01 00:14:21.103278 | TASK [Run test-setup role]
2025-12-01 00:14:21.127740 | controller | ok
2025-12-01 00:14:21.152087 | 
2025-12-01 00:14:21.152179 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-12-01 00:14:21.373208 | controller | ok
2025-12-01 00:14:21.388790 | 
2025-12-01 00:14:21.388928 | TASK [test-setup : Run tools/test-setup.sh]
2025-12-01 00:14:21.940872 | controller | skipping: Conditional result was False
2025-12-01 00:14:21.999970 | 
2025-12-01 00:14:22.000097 | PLAY RECAP
2025-12-01 00:14:22.000154 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-01 00:14:22.000183 | 
2025-12-01 00:14:22.132463 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-01 00:14:22.134543 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-12-01 00:14:22.826614 | 
2025-12-01 00:14:22.826763 | PLAY [controller]
2025-12-01 00:14:22.846467 | 
2025-12-01 00:14:22.846539 | TASK [Create the /root directory]
2025-12-01 00:14:23.421930 | controller | ok
2025-12-01 00:14:23.434933 | 
2025-12-01 00:14:23.435039 | TASK [Install glibc-langpack-en]
2025-12-01 00:14:27.501515 | controller | ok: Nothing to do
2025-12-01 00:14:27.508279 | 
2025-12-01 00:14:27.508349 | TASK [Ensure controller directory exists]
2025-12-01 00:14:27.725239 | controller | changed
2025-12-01 00:14:27.730691 | 
2025-12-01 00:14:27.730756 | TASK [Install container runtime]
2025-12-01 00:14:27.803444 | controller | ok
2025-12-01 00:14:27.849156 | 
2025-12-01 00:14:27.849284 | LOOP [ensure-podman : Find distribution installation]
2025-12-01 00:14:27.886037 | controller | ok: "/var/lib/zuul/builds/b8624190bd8e443f9005d5dd76b11a5b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml"
2025-12-01 00:14:27.901346 | controller | included: /var/lib/zuul/builds/b8624190bd8e443f9005d5dd76b11a5b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml
2025-12-01 00:14:27.907610 | 
2025-12-01 00:14:27.907693 | TASK [ensure-podman : Install podman (RedHat)]
2025-12-01 00:15:39.956968 | controller | changed
2025-12-01 00:15:39.972523 | 
2025-12-01 00:15:39.972710 | TASK [ensure-podman : Fetch podman version]
2025-12-01 00:15:40.502292 | controller | Client:       Podman Engine
2025-12-01 00:15:40.535370 | controller | Version:      4.6.2
2025-12-01 00:15:40.535407 | controller | API Version:  4.6.2
2025-12-01 00:15:40.535421 | controller | Go Version:   go1.19.12
2025-12-01 00:15:40.535445 | controller | Built:        Mon Aug 28 19:38:31 2023
2025-12-01 00:15:40.535458 | controller | OS/Arch:      linux/amd64
2025-12-01 00:15:41.024856 | controller | ok: Runtime: 0:00:00.185516
2025-12-01 00:15:41.038976 | 
2025-12-01 00:15:41.039123 | TASK [ensure-podman : Print podman version installed]
2025-12-01 00:15:41.078291 | Podman version: Client:       Podman Engine
2025-12-01 00:15:41.078514 | Version:      4.6.2
2025-12-01 00:15:41.078573 | API Version:  4.6.2
2025-12-01 00:15:41.078617 | Go Version:   go1.19.12
2025-12-01 00:15:41.078657 | Built:        Mon Aug 28 19:38:31 2023
2025-12-01 00:15:41.078742 | OS/Arch:      linux/amd64
2025-12-01 00:15:41.091404 | 
2025-12-01 00:15:41.091533 | TASK [ensure-podman : Validate podman engine]
2025-12-01 00:15:41.641895 | controller | skipping: Conditional result was False
2025-12-01 00:15:41.655527 | 
2025-12-01 00:15:41.655711 | TASK [ensure-podman : Set up docker compatability socket]
2025-12-01 00:15:41.682375 | controller | skipping: Conditional result was False
2025-12-01 00:15:41.705892 | 
2025-12-01 00:15:41.706027 | TASK [Ensure python3.8 is present]
2025-12-01 00:15:41.732987 | controller | skipping: Conditional result was False
2025-12-01 00:15:41.745484 | 
2025-12-01 00:15:41.745619 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-12-01 00:15:41.774854 | controller | ok
2025-12-01 00:15:41.807352 | 
2025-12-01 00:15:41.807472 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-12-01 00:15:43.164758 | controller | ok: Nothing to do
2025-12-01 00:15:43.177199 | 
2025-12-01 00:15:43.177349 | TASK [our-ensure-python : Also install python3-devel]
2025-12-01 00:15:52.544140 | controller | changed
2025-12-01 00:15:52.568426 | 
2025-12-01 00:15:52.568579 | TASK [Run ensure-virtualenv role]
2025-12-01 00:15:52.592651 | controller | ok
2025-12-01 00:15:52.621460 | 
2025-12-01 00:15:52.621561 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-12-01 00:15:52.878560 | controller | /usr/bin/virtualenv
2025-12-01 00:15:53.405089 | controller | ok: Runtime: 0:00:00.003524
2025-12-01 00:15:53.418266 | 
2025-12-01 00:15:53.418418 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-12-01 00:15:53.455480 | controller | skipping: Conditional result was False
2025-12-01 00:15:53.456253 | controller | ok: All items complete
2025-12-01 00:15:53.456339 | 
2025-12-01 00:15:53.492017 | 
2025-12-01 00:15:53.492594 | TASK [Find the full path of the Python interpreter]
2025-12-01 00:15:53.729465 | controller | /usr/bin/python3
2025-12-01 00:15:54.042815 | controller | ok
2025-12-01 00:15:54.055231 | 
2025-12-01 00:15:54.055377 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-12-01 00:20:32.113597 | [Zuul] Log Stream did not terminate
2025-12-01 00:20:32.114034 | controller | ERROR
2025-12-01 00:20:32.114210 | controller | {
2025-12-01 00:20:32.114264 | controller |   "msg": "Data could not be sent to remote host \"162.253.55.58\". Make sure this host can be reached over ssh: ssh: connect to host 162.253.55.58 port 22: No route to host\r\n",
2025-12-01 00:20:32.114316 | controller |   "unreachable": true
2025-12-01 00:20:32.114352 | controller | }
unreachable
2025-12-01 00:20:32.118178 | 
2025-12-01 00:20:32.118285 | PLAY RECAP
2025-12-01 00:20:32.118374 | controller | ok: 11 changed: 5 unreachable: 1 failed: 0 skipped: 4 rescued: 0 ignored: 0
2025-12-01 00:20:32.118420 | 
2025-12-01 00:20:32.241359 | PRE-RUN END RESULT_UNREACHABLE: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-12-01 00:20:32.243456 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-01 00:20:32.828396 | 
2025-12-01 00:20:32.828505 | PLAY [all]
2025-12-01 00:20:32.850206 | 
2025-12-01 00:20:32.850287 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-12-01 00:20:59.245972 | controller | changed: non-zero return code
2025-12-01 00:20:59.298438 | 
2025-12-01 00:20:59.298694 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-12-01 00:20:59.324768 | controller | skipping: Conditional result was False
2025-12-01 00:20:59.334293 | 
2025-12-01 00:20:59.334392 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-12-01 00:20:59.369452 | 
2025-12-01 00:20:59.369789 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-12-01 00:20:59.403915 | 
2025-12-01 00:20:59.404091 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-12-01 00:20:59.429174 | controller | skipping: Conditional result was False
2025-12-01 00:20:59.438430 | 
2025-12-01 00:20:59.438526 | LOOP [fetch-subunit-output : Generate subunit file]
2025-12-01 00:20:59.472635 | 
2025-12-01 00:20:59.472842 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-12-01 00:20:59.498037 | controller | skipping: Conditional result was False
2025-12-01 00:20:59.510699 | 
2025-12-01 00:20:59.510820 | TASK [fetch-subunit-output : Remove the temporary file]
2025-12-01 00:20:59.536570 | controller | skipping: Conditional result was False
2025-12-01 00:20:59.544865 | 
2025-12-01 00:20:59.544957 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-12-01 00:20:59.560314 | controller | skipping: Conditional result was False
2025-12-01 00:20:59.596603 | 
2025-12-01 00:20:59.596725 | PLAY RECAP
2025-12-01 00:20:59.596780 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-01 00:20:59.596807 | 
2025-12-01 00:20:59.704796 | POST-RUN END RESULT_UNREACHABLE: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-01 00:20:59.706482 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-12-01 00:21:00.321744 | 
2025-12-01 00:21:00.321864 | PLAY [all:!appliance*]
2025-12-01 00:21:00.343176 | 
2025-12-01 00:21:00.343259 | TASK [unregister the node]
2025-12-01 00:21:00.875502 | controller | skipping: Conditional result was False
2025-12-01 00:21:00.888668 | 
2025-12-01 00:21:00.888851 | TASK [include_role : fetch-output]
2025-12-01 00:21:00.939265 | controller | ok
2025-12-01 00:21:00.976251 | 
2025-12-01 00:21:00.976345 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-01 00:21:01.042406 | controller | skipping: Conditional result was False
2025-12-01 00:21:01.051701 | 
2025-12-01 00:21:01.051854 | TASK [fetch-output : Set log path for single node]
2025-12-01 00:21:01.100236 | controller | ok
2025-12-01 00:21:01.112773 | 
2025-12-01 00:21:01.112916 | LOOP [fetch-output : Ensure local output dirs]
2025-12-01 00:21:01.610855 | controller -> localhost | ok: "/var/lib/zuul/builds/b8624190bd8e443f9005d5dd76b11a5b/work/logs"
2025-12-01 00:21:01.829885 | controller -> localhost | changed: "/var/lib/zuul/builds/b8624190bd8e443f9005d5dd76b11a5b/work/artifacts"
2025-12-01 00:21:02.053639 | controller -> localhost | changed: "/var/lib/zuul/builds/b8624190bd8e443f9005d5dd76b11a5b/work/docs"
2025-12-01 00:21:02.065575 | 
2025-12-01 00:21:02.065643 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-01 00:21:02.832401 | controller | changed:
2025-12-01 00:21:02.832827 | controller | .d..t...... ./
2025-12-01 00:21:02.832892 | controller | cd+++++++++ controller/
2025-12-01 00:21:02.832963 | controller | changed: All items complete
2025-12-01 00:21:02.833008 | 
2025-12-01 00:21:03.371248 | controller | changed: .d..t...... ./
2025-12-01 00:21:03.950628 | controller | changed: .d..t...... ./
2025-12-01 00:21:03.982369 | 
2025-12-01 00:21:03.982460 | TASK [include_role : fetch-output-openshift]
2025-12-01 00:21:04.007873 | controller | skipping: Conditional result was False
2025-12-01 00:21:04.021438 | 
2025-12-01 00:21:04.021584 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-01 00:21:04.071914 | controller | skipping: Conditional result was False
2025-12-01 00:21:04.082925 | controller | skipping: Conditional result was False
2025-12-01 00:21:04.139909 | 
2025-12-01 00:21:04.140053 | PLAY [localhost]
2025-12-01 00:21:04.156594 | 
2025-12-01 00:21:04.156706 | TASK [Run Zuul manifest role]
2025-12-01 00:21:04.176975 | localhost | ok
2025-12-01 00:21:04.196021 | 
2025-12-01 00:21:04.196105 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-01 00:21:04.591713 | localhost | changed
2025-12-01 00:21:04.596433 | 
2025-12-01 00:21:04.596497 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-01 00:21:04.637058 | localhost | ok
2025-12-01 00:21:04.644790 | 
2025-12-01 00:21:04.644849 | TASK [Set zuul-log-path fact]
2025-12-01 00:21:04.663087 | localhost | ok
2025-12-01 00:21:04.676824 | 
2025-12-01 00:21:04.676886 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-01 00:21:04.714998 | localhost | ok
2025-12-01 00:21:04.722730 | 
2025-12-01 00:21:04.722786 | LOOP [Run upload-logs-swift role]
2025-12-01 00:21:04.757277 | localhost | Output suppressed because no_log was given
2025-12-01 00:21:04.781743 | 
2025-12-01 00:21:04.781833 | TASK [Set zuul-log-path fact]
2025-12-01 00:21:04.805809 | localhost | skipping: Conditional result was False
2025-12-01 00:21:04.811007 | 
2025-12-01 00:21:04.811068 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-12-01 00:21:05.209860 | localhost -> localhost | ok: Runtime: 0:00:00.005824
2025-12-01 00:21:05.241619 | 
2025-12-01 00:21:05.241763 | TASK [upload-logs-swift : Upload logs to swift]