2025-10-19 00:14:52.124731 | Job console starting...
2025-10-19 00:14:52.136429 | Updating repositories
2025-10-19 00:14:52.261491 | Preparing job workspace
2025-10-19 00:14:57.948462 | Running Ansible setup...
2025-10-19 00:15:05.639855 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-10-19 00:15:06.308628 | 
2025-10-19 00:15:06.308776 | PLAY [localhost]
2025-10-19 00:15:06.318096 | 
2025-10-19 00:15:06.318211 | TASK [Gathering Facts]
2025-10-19 00:15:07.388548 | localhost | ok
2025-10-19 00:15:07.401786 | 
2025-10-19 00:15:07.401897 | TASK [Setup log path fact]
2025-10-19 00:15:07.420240 | localhost | ok
2025-10-19 00:15:07.434066 | 
2025-10-19 00:15:07.434164 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-19 00:15:07.452193 | localhost | ok
2025-10-19 00:15:07.460058 | 
2025-10-19 00:15:07.460148 | TASK [emit-job-header : Print job information]
2025-10-19 00:15:07.488516 | # Job Information
2025-10-19 00:15:07.488713 | Ansible Version: 2.15.12
2025-10-19 00:15:07.488762 | Job: ansible-test-sanity-docker-stable-2.9
2025-10-19 00:15:07.488799 | Pipeline: periodic
2025-10-19 00:15:07.488828 | Executor: ze01.softwarefactory-project.io
2025-10-19 00:15:07.488855 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-10-19 00:15:07.488886 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/38e/ansible/38eb9dee3f0d4f1f94732218991f0a7a/
2025-10-19 00:15:07.488915 | Event ID: 2ecd1ab1c8684561a8737a385800b3f1
2025-10-19 00:15:07.494347 | 
2025-10-19 00:15:07.494444 | LOOP [emit-job-header : Print node information]
2025-10-19 00:15:07.604786 | localhost | ok:
2025-10-19 00:15:07.605010 | localhost | # Node Information
2025-10-19 00:15:07.605059 | localhost | Inventory Hostname: controller
2025-10-19 00:15:07.605080 | localhost | Hostname: np0005494630
2025-10-19 00:15:07.605099 | localhost | Username: zuul
2025-10-19 00:15:07.605120 | localhost | Distro: Fedora 37
2025-10-19 00:15:07.605137 | localhost | Provider: ansible-vexxhost-ams1
2025-10-19 00:15:07.605154 | localhost | Region: ams1
2025-10-19 00:15:07.605171 | localhost | Label: ansible-fedora-37-1vcpu
2025-10-19 00:15:07.605187 | localhost | Product Name: OpenStack Nova
2025-10-19 00:15:07.605204 | localhost | Interface IP: 38.129.16.75
2025-10-19 00:15:07.627636 | 
2025-10-19 00:15:07.627805 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-10-19 00:15:08.041626 | localhost -> localhost | changed
2025-10-19 00:15:08.047375 | 
2025-10-19 00:15:08.047461 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-10-19 00:15:09.067601 | localhost -> localhost | changed
2025-10-19 00:15:09.099801 | 
2025-10-19 00:15:09.099916 | PLAY [all:!appliance*]
2025-10-19 00:15:09.124762 | 
2025-10-19 00:15:09.124921 | TASK [include_role : start-zuul-console]
2025-10-19 00:15:09.146892 | controller | ok
2025-10-19 00:15:09.163343 | 
2025-10-19 00:15:09.163476 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-10-19 00:15:10.262710 | controller | ok
2025-10-19 00:15:10.274967 | 
2025-10-19 00:15:10.275121 | TASK [use-our-mirror : Retrieve the IP address]
2025-10-19 00:15:12.960545 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-10-19 00:15:12.970271 | 
2025-10-19 00:15:12.970440 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-10-19 00:15:13.334852 | controller | skipping: Conditional result was False
2025-10-19 00:15:13.344564 | 
2025-10-19 00:15:13.344768 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-10-19 00:15:13.372505 | controller | skipping: Conditional result was False
2025-10-19 00:15:13.382782 | 
2025-10-19 00:15:13.382934 | TASK [use-our-mirror : Create the podman configuration directory]
2025-10-19 00:15:13.408522 | controller | skipping: Conditional result was False
2025-10-19 00:15:13.418650 | 
2025-10-19 00:15:13.418811 | TASK [use-our-mirror : Copy the podman configuration]
2025-10-19 00:15:13.444161 | controller | skipping: Conditional result was False
2025-10-19 00:15:13.452794 | 
2025-10-19 00:15:13.452957 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-10-19 00:15:13.479329 | controller | skipping: Conditional result was False
2025-10-19 00:15:13.488007 | 
2025-10-19 00:15:13.488183 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-10-19 00:15:13.502332 | controller | skipping: Conditional result was False
2025-10-19 00:15:13.515132 | 
2025-10-19 00:15:13.515270 | TASK [Disable Fedora Modular]
2025-10-19 00:15:14.705270 | controller | changed
2025-10-19 00:15:14.713626 | 
2025-10-19 00:15:14.713729 | TASK [Enable EPEL]
2025-10-19 00:15:14.738136 | controller | skipping: Conditional result was False
2025-10-19 00:15:14.747171 | 
2025-10-19 00:15:14.747299 | TASK [Register the RHEL node]
2025-10-19 00:15:15.159298 | 
2025-10-19 00:15:15.159516 | TASK [Show the subscription-manager status]
2025-10-19 00:15:15.530384 | controller | skipping: Conditional result was False
2025-10-19 00:15:15.539618 | 
2025-10-19 00:15:15.539760 | TASK [Enable EPEL on RHEL]
2025-10-19 00:15:15.919368 | controller | skipping: Conditional result was False
2025-10-19 00:15:15.926367 | 
2025-10-19 00:15:15.926444 | TASK [Install git and tox]
2025-10-19 00:17:16.644364 | controller | changed
2025-10-19 00:17:16.652540 | 
2025-10-19 00:17:16.652660 | TASK [include_role : prepare-workspace]
2025-10-19 00:17:16.684813 | controller | ok
2025-10-19 00:17:16.708822 | 
2025-10-19 00:17:16.708952 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-10-19 00:17:17.557314 | controller | ok
2025-10-19 00:17:17.564631 | 
2025-10-19 00:17:17.564736 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-10-19 00:17:37.697265 | controller | Output suppressed because no_log was given
2025-10-19 00:17:37.714083 | 
2025-10-19 00:17:37.714219 | TASK [include_role : prepare-workspace-openshift]
2025-10-19 00:17:37.735802 | controller | skipping: Conditional result was False
2025-10-19 00:17:37.771650 | 
2025-10-19 00:17:37.771748 | PLAY [all:!appliance]
2025-10-19 00:17:37.796882 | 
2025-10-19 00:17:37.797034 | TASK [Run add-build-sshkey role (RSA)]
2025-10-19 00:17:37.832781 | controller | ok
2025-10-19 00:17:37.854764 | 
2025-10-19 00:17:37.854916 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-19 00:17:38.117773 | controller -> localhost | ok
2025-10-19 00:17:38.124968 | 
2025-10-19 00:17:38.125063 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-19 00:17:38.156406 | controller | ok
2025-10-19 00:17:38.172894 | controller | included: /var/lib/zuul/builds/38eb9dee3f0d4f1f94732218991f0a7a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-19 00:17:38.179307 | 
2025-10-19 00:17:38.179378 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-19 00:17:38.899504 | controller -> localhost | Generating public/private rsa key pair.
2025-10-19 00:17:38.899835 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/38eb9dee3f0d4f1f94732218991f0a7a/work/38eb9dee3f0d4f1f94732218991f0a7a_id_rsa.
2025-10-19 00:17:38.899900 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/38eb9dee3f0d4f1f94732218991f0a7a/work/38eb9dee3f0d4f1f94732218991f0a7a_id_rsa.pub.
2025-10-19 00:17:38.899948 | controller -> localhost | The key fingerprint is:
2025-10-19 00:17:38.899992 | controller -> localhost | SHA256:kXS4eyTp5T15sCiPQ2UqbMLj98k+eK35P28W7NcuXN8 zuul-build-sshkey
2025-10-19 00:17:38.900080 | controller -> localhost | The key's randomart image is:
2025-10-19 00:17:38.900126 | controller -> localhost | +---[RSA 2048]----+
2025-10-19 00:17:38.900168 | controller -> localhost | |        ...      |
2025-10-19 00:17:38.900207 | controller -> localhost | |       ..o       |
2025-10-19 00:17:38.900247 | controller -> localhost | |        oo       |
2025-10-19 00:17:38.900284 | controller -> localhost | |        +.= .    |
2025-10-19 00:17:38.900322 | controller -> localhost | |   . . .SX o =   |
2025-10-19 00:17:38.900372 | controller -> localhost | |    + + * + = + .|
2025-10-19 00:17:38.900420 | controller -> localhost | |   . + + *   = o+|
2025-10-19 00:17:38.900482 | controller -> localhost | |    . o.=oo . * E|
2025-10-19 00:17:38.900514 | controller -> localhost | |     . +B=...=.+.|
2025-10-19 00:17:38.900554 | controller -> localhost | +----[SHA256]-----+
2025-10-19 00:17:38.900638 | controller -> localhost | ok: Runtime: 0:00:00.251020
2025-10-19 00:17:38.913769 | 
2025-10-19 00:17:38.913939 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-19 00:17:38.948620 | controller | ok
2025-10-19 00:17:38.961729 | controller | included: /var/lib/zuul/builds/38eb9dee3f0d4f1f94732218991f0a7a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-19 00:17:38.972295 | 
2025-10-19 00:17:38.972419 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-19 00:17:38.996902 | controller | skipping: Conditional result was False
2025-10-19 00:17:39.003855 | 
2025-10-19 00:17:39.003974 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-19 00:17:40.068245 | controller | changed
2025-10-19 00:17:40.075734 | 
2025-10-19 00:17:40.075849 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-19 00:17:40.720479 | controller | ok
2025-10-19 00:17:40.733723 | 
2025-10-19 00:17:40.733934 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-19 00:17:43.752666 | controller | changed
2025-10-19 00:17:43.760794 | 
2025-10-19 00:17:43.761101 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-19 00:17:46.795502 | controller | changed
2025-10-19 00:17:46.808345 | 
2025-10-19 00:17:46.808519 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-19 00:17:46.834619 | controller | skipping: Conditional result was False
2025-10-19 00:17:46.842838 | 
2025-10-19 00:17:46.843258 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-19 00:17:47.179455 | controller -> localhost | changed
2025-10-19 00:17:47.208865 | 
2025-10-19 00:17:47.209124 | TASK [add-build-sshkey : Add back temp key]
2025-10-19 00:17:47.500838 | controller -> localhost | Identity added: /var/lib/zuul/builds/38eb9dee3f0d4f1f94732218991f0a7a/work/38eb9dee3f0d4f1f94732218991f0a7a_id_rsa (zuul-build-sshkey)
2025-10-19 00:17:47.501122 | controller -> localhost | ok: Runtime: 0:00:00.013852
2025-10-19 00:17:47.509446 | 
2025-10-19 00:17:47.509572 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-19 00:17:48.361535 | controller | ok
2025-10-19 00:17:48.377561 | 
2025-10-19 00:17:48.377704 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-19 00:17:48.415497 | controller | skipping: Conditional result was False
2025-10-19 00:17:48.443744 | 
2025-10-19 00:17:48.443941 | TASK [Run add-build-sshkey role (ECDSA)]
2025-10-19 00:17:48.480853 | controller | ok
2025-10-19 00:17:48.509291 | 
2025-10-19 00:17:48.509457 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-10-19 00:17:48.761394 | controller -> localhost | ok
2025-10-19 00:17:48.772437 | 
2025-10-19 00:17:48.772573 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-10-19 00:17:48.816798 | controller | ok
2025-10-19 00:17:48.836003 | controller | included: /var/lib/zuul/builds/38eb9dee3f0d4f1f94732218991f0a7a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-10-19 00:17:48.845263 | 
2025-10-19 00:17:48.845360 | TASK [add-build-sshkey : Create Temp SSH key]
2025-10-19 00:17:49.159864 | controller -> localhost | Generating public/private ecdsa key pair.
2025-10-19 00:17:49.160151 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/38eb9dee3f0d4f1f94732218991f0a7a/work/38eb9dee3f0d4f1f94732218991f0a7a_id_ecdsa.
2025-10-19 00:17:49.160194 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/38eb9dee3f0d4f1f94732218991f0a7a/work/38eb9dee3f0d4f1f94732218991f0a7a_id_ecdsa.pub.
2025-10-19 00:17:49.160232 | controller -> localhost | The key fingerprint is:
2025-10-19 00:17:49.160253 | controller -> localhost | SHA256:/xiPjxeDWjZEVxqCuFJH67OViZEZkhuTMLm2HAcIKYE zuul-build-sshkey
2025-10-19 00:17:49.160272 | controller -> localhost | The key's randomart image is:
2025-10-19 00:17:49.160292 | controller -> localhost | +---[ECDSA 521]---+
2025-10-19 00:17:49.160313 | controller -> localhost | |++ .oo.=o.. ...  |
2025-10-19 00:17:49.160332 | controller -> localhost | |E . o.B.o=...o   |
2025-10-19 00:17:49.160351 | controller -> localhost | |.    + *=. ..    |
2025-10-19 00:17:49.160369 | controller -> localhost | |    = +. o.o     |
2025-10-19 00:17:49.160387 | controller -> localhost | |   o =  S.+.     |
2025-10-19 00:17:49.160405 | controller -> localhost | |    o    == o    |
2025-10-19 00:17:49.160424 | controller -> localhost | |        .+o. o   |
2025-10-19 00:17:49.160443 | controller -> localhost | |        .  B.    |
2025-10-19 00:17:49.160461 | controller -> localhost | |          +o+    |
2025-10-19 00:17:49.160479 | controller -> localhost | +----[SHA256]-----+
2025-10-19 00:17:49.160535 | controller -> localhost | ok: Runtime: 0:00:00.008204
2025-10-19 00:17:49.170803 | 
2025-10-19 00:17:49.170911 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-10-19 00:17:49.192671 | controller | ok
2025-10-19 00:17:49.201848 | controller | included: /var/lib/zuul/builds/38eb9dee3f0d4f1f94732218991f0a7a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-10-19 00:17:49.213432 | 
2025-10-19 00:17:49.213538 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-10-19 00:17:49.238272 | controller | skipping: Conditional result was False
2025-10-19 00:17:49.245570 | 
2025-10-19 00:17:49.245690 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-10-19 00:17:50.113086 | controller | changed
2025-10-19 00:17:50.120442 | 
2025-10-19 00:17:50.120606 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-10-19 00:17:50.746691 | controller | ok
2025-10-19 00:17:50.756361 | 
2025-10-19 00:17:50.756529 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-10-19 00:17:53.735103 | controller | changed
2025-10-19 00:17:53.744956 | 
2025-10-19 00:17:53.745041 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-10-19 00:17:56.636891 | controller | changed
2025-10-19 00:17:56.645556 | 
2025-10-19 00:17:56.645699 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-10-19 00:17:56.672466 | controller | skipping: Conditional result was False
2025-10-19 00:17:56.681797 | 
2025-10-19 00:17:56.681945 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-10-19 00:17:56.953784 | controller -> localhost | changed
2025-10-19 00:17:56.971581 | 
2025-10-19 00:17:56.971744 | TASK [add-build-sshkey : Add back temp key]
2025-10-19 00:17:57.288598 | controller -> localhost | Identity added: /var/lib/zuul/builds/38eb9dee3f0d4f1f94732218991f0a7a/work/38eb9dee3f0d4f1f94732218991f0a7a_id_ecdsa (zuul-build-sshkey)
2025-10-19 00:17:57.288886 | controller -> localhost | ok: Runtime: 0:00:00.008000
2025-10-19 00:17:57.298139 | 
2025-10-19 00:17:57.298428 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-10-19 00:17:57.984241 | controller | ok
2025-10-19 00:17:57.993231 | 
2025-10-19 00:17:57.993393 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-10-19 00:17:58.020826 | controller | skipping: Conditional result was False
2025-10-19 00:17:58.040510 | 
2025-10-19 00:17:58.040651 | TASK [include_role : remove-zuul-sshkey]
2025-10-19 00:17:58.069182 | controller | skipping: Conditional result was False
2025-10-19 00:17:58.076193 | 
2025-10-19 00:17:58.076464 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-10-19 00:17:58.730518 | controller | ok: "logs"
2025-10-19 00:17:58.731105 | controller | ok: All items complete
2025-10-19 00:17:58.731179 | 
2025-10-19 00:17:59.335765 | controller | ok: "artifacts"
2025-10-19 00:17:59.956611 | controller | ok: "docs"
2025-10-19 00:17:59.964342 | 
2025-10-19 00:17:59.964758 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-10-19 00:18:00.636607 | controller | changed: "logs"
2025-10-19 00:18:01.275876 | controller | changed: "artifacts"
2025-10-19 00:18:01.921654 | controller | changed: "docs"
2025-10-19 00:18:01.956664 | 
2025-10-19 00:18:01.956765 | PLAY RECAP
2025-10-19 00:18:01.956807 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-10-19 00:18:01.956833 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-10-19 00:18:01.956851 | 
2025-10-19 00:18:02.113195 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-10-19 00:18:02.115598 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-10-19 00:18:02.891580 | 
2025-10-19 00:18:02.891719 | PLAY [all]
2025-10-19 00:18:02.918111 | 
2025-10-19 00:18:02.918279 | TASK [Install binary dependencies]
2025-10-19 00:18:02.969947 | controller | ok
2025-10-19 00:18:02.991775 | 
2025-10-19 00:18:02.991913 | TASK [bindep : Include find tasks]
2025-10-19 00:18:03.021960 | controller | ok
2025-10-19 00:18:03.030575 | controller | included: /var/lib/zuul/builds/38eb9dee3f0d4f1f94732218991f0a7a/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-10-19 00:18:03.036967 | 
2025-10-19 00:18:03.037051 | TASK [bindep : Look for bindep.txt]
2025-10-19 00:18:03.980495 | controller | ok
2025-10-19 00:18:03.988805 | 
2025-10-19 00:18:03.988914 | TASK [bindep : Define bindep_file fact]
2025-10-19 00:18:04.014737 | controller | skipping: Conditional result was False
2025-10-19 00:18:04.023474 | 
2025-10-19 00:18:04.023587 | TASK [bindep : Look for other-requirements.txt]
2025-10-19 00:18:04.675981 | controller | ok
2025-10-19 00:18:04.690901 | 
2025-10-19 00:18:04.691169 | TASK [bindep : Define bindep_file fact]
2025-10-19 00:18:04.718905 | controller | skipping: Conditional result was False
2025-10-19 00:18:04.733244 | 
2025-10-19 00:18:04.733454 | TASK [bindep : Look for bindep fallback file]
2025-10-19 00:18:04.770992 | controller | skipping: Conditional result was False
2025-10-19 00:18:04.785093 | 
2025-10-19 00:18:04.785293 | TASK [bindep : Define bindep_file fact]
2025-10-19 00:18:04.813544 | controller | skipping: Conditional result was False
2025-10-19 00:18:04.828984 | 
2025-10-19 00:18:04.829262 | TASK [bindep : Include bindep tasks]
2025-10-19 00:18:04.856699 | controller | skipping: Conditional result was False
2025-10-19 00:18:04.867515 | 
2025-10-19 00:18:04.867670 | TASK [bindep : Include install tasks]
2025-10-19 00:18:04.894249 | controller | skipping: Conditional result was False
2025-10-19 00:18:04.905241 | 
2025-10-19 00:18:04.905400 | LOOP [bindep : Include package tasks]
2025-10-19 00:18:04.975736 | 
2025-10-19 00:18:04.975914 | TASK [Run test-setup role]
2025-10-19 00:18:04.996540 | controller | ok
2025-10-19 00:18:05.016209 | 
2025-10-19 00:18:05.016338 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-10-19 00:18:05.730429 | controller | ok
2025-10-19 00:18:05.744503 | 
2025-10-19 00:18:05.744638 | TASK [test-setup : Run tools/test-setup.sh]
2025-10-19 00:18:06.125921 | controller | skipping: Conditional result was False
2025-10-19 00:18:06.155248 | 
2025-10-19 00:18:06.155354 | PLAY RECAP
2025-10-19 00:18:06.155393 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-10-19 00:18:06.155414 | 
2025-10-19 00:18:06.284132 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-10-19 00:18:06.285331 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-10-19 00:18:06.884357 | 
2025-10-19 00:18:06.884542 | PLAY [controller]
2025-10-19 00:18:06.907264 | 
2025-10-19 00:18:06.907412 | TASK [Create the /root directory]
2025-10-19 00:18:07.961684 | controller | ok
2025-10-19 00:18:07.972185 | 
2025-10-19 00:18:07.972347 | TASK [Install glibc-langpack-en]
2025-10-19 00:18:15.724912 | controller | ok: Nothing to do
2025-10-19 00:18:15.730707 | 
2025-10-19 00:18:15.730777 | TASK [Ensure controller directory exists]
2025-10-19 00:18:16.562571 | controller | changed
2025-10-19 00:18:16.569153 | 
2025-10-19 00:18:16.569238 | TASK [Install container runtime]
2025-10-19 00:18:16.611052 | controller | ok
2025-10-19 00:18:16.655477 | 
2025-10-19 00:18:16.655604 | LOOP [ensure-docker : Gather variables for each operating system]
2025-10-19 00:18:16.687596 | controller | ok: "/var/lib/zuul/builds/38eb9dee3f0d4f1f94732218991f0a7a/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-10-19 00:18:16.696838 | 
2025-10-19 00:18:16.696960 | TASK [ensure-docker : Sanity check for distro selection]
2025-10-19 00:18:16.713585 | controller | skipping: Conditional result was False
2025-10-19 00:18:16.722551 | 
2025-10-19 00:18:16.722701 | TASK [ensure-docker : Sanity check for distro install]
2025-10-19 00:18:16.748077 | controller | skipping: Conditional result was False
2025-10-19 00:18:16.758877 | 
2025-10-19 00:18:16.759050 | TASK [ensure-docker : Sanity check for upstream install]
2025-10-19 00:18:16.784447 | controller | skipping: Conditional result was False
2025-10-19 00:18:16.791122 | 
2025-10-19 00:18:16.791237 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-10-19 00:18:16.815731 | controller | skipping: Conditional result was False
2025-10-19 00:18:16.822313 | 
2025-10-19 00:18:16.822399 | TASK [ensure-docker : Include tasks for docker install]
2025-10-19 00:18:16.842420 | controller | ok
2025-10-19 00:18:16.851193 | controller | included: /var/lib/zuul/builds/38eb9dee3f0d4f1f94732218991f0a7a/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-10-19 00:18:16.860181 | 
2025-10-19 00:18:16.860312 | TASK [ensure-docker : Not Implemented]
2025-10-19 00:18:16.875385 | controller | skipping: Conditional result was False
2025-10-19 00:18:16.883719 | 
2025-10-19 00:18:16.883835 | TASK [Set up docker mirrors]
2025-10-19 00:18:16.934071 | controller | ok
2025-10-19 00:18:16.953692 | 
2025-10-19 00:18:16.953810 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-10-19 00:18:16.988124 | controller | skipping: Conditional result was False
2025-10-19 00:18:16.997323 | 
2025-10-19 00:18:16.997455 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-10-19 00:18:17.033419 | controller | skipping: Conditional result was False
2025-10-19 00:18:17.047632 | 
2025-10-19 00:18:17.047820 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-10-19 00:18:17.115669 | controller | skipping: Conditional result was False
2025-10-19 00:18:17.150623 | 
2025-10-19 00:18:17.150745 | TASK [use-docker-mirror : Set up docker mirrors]
2025-10-19 00:18:17.174875 | controller | skipping: Conditional result was False
2025-10-19 00:18:17.186671 | 
2025-10-19 00:18:17.186775 | TASK [Add all repositories]
2025-10-19 00:18:17.206328 | controller | ok
2025-10-19 00:18:17.231878 | 
2025-10-19 00:18:17.231998 | LOOP [ensure-package-repositories : Install repositories]
2025-10-19 00:18:17.265102 | controller | ok: "/var/lib/zuul/builds/38eb9dee3f0d4f1f94732218991f0a7a/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-10-19 00:18:17.265275 | controller | ok: All items complete
2025-10-19 00:18:17.265304 | 
2025-10-19 00:18:17.274259 | controller | included: /var/lib/zuul/builds/38eb9dee3f0d4f1f94732218991f0a7a/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-10-19 00:18:17.280433 | 
2025-10-19 00:18:17.280498 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-10-19 00:18:18.230944 | controller | changed
2025-10-19 00:18:18.246812 | 
2025-10-19 00:18:18.246997 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-10-19 00:18:21.435925 | controller | changed:
2025-10-19 00:18:21.436252 | controller | {
2025-10-19 00:18:21.436319 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2025-10-19 00:18:21.436377 | controller | }
2025-10-19 00:18:21.453249 | 
2025-10-19 00:18:21.453452 | LOOP [ensure-package-repositories : Import GPG keys]
2025-10-19 00:18:22.852434 | controller | changed:
2025-10-19 00:18:22.852765 | controller | {
2025-10-19 00:18:22.852805 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2025-10-19 00:18:22.852839 | controller | }
2025-10-19 00:18:22.852889 | controller | changed: All items complete
2025-10-19 00:18:22.852918 | 
2025-10-19 00:18:22.870692 | 
2025-10-19 00:18:22.870926 | TASK [ensure-package-repositories : Remove GPG temp directory]
2025-10-19 00:18:23.552530 | controller | changed
2025-10-19 00:18:23.561607 | 
2025-10-19 00:18:23.561772 | LOOP [ensure-package-repositories : Add repositories]
2025-10-19 00:18:24.442312 | controller | changed:
2025-10-19 00:18:24.442579 | controller | {
2025-10-19 00:18:24.442607 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2025-10-19 00:18:24.442628 | controller |   "description": "Docker CE Stable - $basearch",
2025-10-19 00:18:24.442646 | controller |   "gpgcheck": true,
2025-10-19 00:18:24.442663 | controller |   "name": "docker-ce-stable"
2025-10-19 00:18:24.442679 | controller | }
2025-10-19 00:18:24.463326 | 
2025-10-19 00:18:24.463470 | TASK [ensure-docker : Install pre-reqs]
2025-10-19 00:18:29.749017 | controller | ok: Nothing to do
2025-10-19 00:18:29.761992 | 
2025-10-19 00:18:29.762179 | TASK [ensure-docker : Remove packages]
2025-10-19 00:18:33.029472 | controller | ok: Nothing to do
2025-10-19 00:18:33.044977 | 
2025-10-19 00:18:33.045221 | TASK [ensure-docker : Install upstream docker using package]
2025-10-19 00:19:53.099456 | controller | changed
2025-10-19 00:19:53.155150 | 
2025-10-19 00:19:53.155293 | TASK [ensure-docker : Setup docker]
2025-10-19 00:19:53.190255 | controller | ok
2025-10-19 00:19:53.201132 | controller | included: /var/lib/zuul/builds/38eb9dee3f0d4f1f94732218991f0a7a/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2025-10-19 00:19:53.213549 | 
2025-10-19 00:19:53.213652 | TASK [ensure-docker : Ensure "docker" group exists]
2025-10-19 00:19:54.177110 | controller | ok
2025-10-19 00:19:54.194177 | 
2025-10-19 00:19:54.194345 | TASK [ensure-docker : Add user to docker group]
2025-10-19 00:19:55.281460 | controller | changed
2025-10-19 00:19:55.287696 | 
2025-10-19 00:19:55.287756 | TASK [Update docker daemon proxy configuration]
2025-10-19 00:19:55.321691 | controller | skipping: Conditional result was False
2025-10-19 00:19:55.328851 | 
2025-10-19 00:19:55.328932 | TASK [Lower default MTU]
2025-10-19 00:19:55.352382 | controller | skipping: Conditional result was False
2025-10-19 00:19:55.360822 | 
2025-10-19 00:19:55.360908 | TASK [ensure-docker : Get the status of the docket.socket unit]
2025-10-19 00:19:56.077013 | controller | inactive
2025-10-19 00:19:56.221878 | controller | ok: Runtime: 0:00:00.016162
2025-10-19 00:19:56.230934 | 
2025-10-19 00:19:56.231087 | TASK [ensure-docker : Get the status of the docket unit]
2025-10-19 00:19:56.856870 | controller | inactive
2025-10-19 00:19:57.092672 | controller | ok: Runtime: 0:00:00.015641
2025-10-19 00:19:57.107794 | 
2025-10-19 00:19:57.107963 | TASK [ensure-docker : Restart docker]
2025-10-19 00:19:57.151916 | Trigger a Docker restart
2025-10-19 00:19:57.158514 | 
2025-10-19 00:19:57.158589 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2025-10-19 00:19:57.188647 | 
2025-10-19 00:19:57.188765 | TASK [ensure-docker : Install docker-compose]
2025-10-19 00:19:57.214321 | controller | skipping: Conditional result was False
2025-10-19 00:19:57.220755 | 
2025-10-19 00:19:57.220826 | TASK [ensure-docker : Flush handlers before role exit]
2025-10-19 00:19:57.231267 | 
2025-10-19 00:19:57.231346 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2025-10-19 00:20:00.681833 | controller | changed
2025-10-19 00:20:00.690563 | 
2025-10-19 00:20:00.690674 | TASK [ensure-docker : Assure docker service is running]
2025-10-19 00:20:04.545482 | controller | changed
2025-10-19 00:20:04.562292 | 
2025-10-19 00:20:04.562427 | TASK [ensure-docker : Assure docker.socket service is running]
2025-10-19 00:20:07.574955 | controller | ok
2025-10-19 00:20:07.586851 | 
2025-10-19 00:20:07.586971 | TASK [ensure-docker : Correct group ownership on docker sock]
2025-10-19 00:20:08.451523 | controller | ok
2025-10-19 00:20:08.459118 | 
2025-10-19 00:20:08.459257 | TASK [ensure-docker : Validate ability to talk with docker]
2025-10-19 00:20:09.138456 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2025-10-19 00:20:09.333379 | controller | ok: Runtime: 0:00:00.061318
2025-10-19 00:20:09.349318 | 
2025-10-19 00:20:09.349483 | TASK [ensure-docker : Get version details]
2025-10-19 00:20:10.043803 | controller | Client: Docker Engine - Community
2025-10-19 00:20:10.043893 | controller |  Version:           24.0.7
2025-10-19 00:20:10.043903 | controller |  API version:       1.43
2025-10-19 00:20:10.043911 | controller |  Go version:        go1.20.10
2025-10-19 00:20:10.043929 | controller |  Git commit:        afdd53b
2025-10-19 00:20:10.043936 | controller |  Built:             Thu Oct 26 09:09:39 2023
2025-10-19 00:20:10.043944 | controller |  OS/Arch:           linux/amd64
2025-10-19 00:20:10.043952 | controller |  Context:           default
2025-10-19 00:20:10.043961 | controller |
2025-10-19 00:20:10.043968 | controller | Server: Docker Engine - Community
2025-10-19 00:20:10.043979 | controller |  Engine:
2025-10-19 00:20:10.043986 | controller |   Version:          24.0.7
2025-10-19 00:20:10.043992 | controller |   API version:      1.43 (minimum version 1.12)
2025-10-19 00:20:10.043998 | controller |   Go version:       go1.20.10
2025-10-19 00:20:10.044006 | controller |   Git commit:       311b9ff
2025-10-19 00:20:10.044013 | controller |   Built:            Thu Oct 26 09:08:11 2023
2025-10-19 00:20:10.044035 | controller |   OS/Arch:          linux/amd64
2025-10-19 00:20:10.044041 | controller |   Experimental:     false
2025-10-19 00:20:10.044048 | controller |  containerd:
2025-10-19 00:20:10.044054 | controller |   Version:          1.6.25
2025-10-19 00:20:10.044060 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2025-10-19 00:20:10.044066 | controller |  runc:
2025-10-19 00:20:10.044105 | controller |   Version:          1.1.10
2025-10-19 00:20:10.044116 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2025-10-19 00:20:10.045908 | controller |  docker-init:
2025-10-19 00:20:10.046007 | controller |   Version:          0.19.0
2025-10-19 00:20:10.046024 | controller |   GitCommit:        de40ad0
2025-10-19 00:20:10.227568 | controller | ok: Runtime: 0:00:00.048614
2025-10-19 00:20:10.240680 | 
2025-10-19 00:20:10.240857 | TASK [ensure-docker : Dump installed docker details]
2025-10-19 00:20:10.291102 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version:           24.0.7\n API version:       1.43\n Go version:        go1.20.10\n Git commit:        afdd53b\n Built:             Thu Oct 26 09:09:39 2023\n OS/Arch:           linux/amd64\n Context:           default\n\nServer: Docker Engine - Community\n Engine:\n  Version:          24.0.7\n  API version:      1.43 (minimum version 1.12)\n  Go version:       go1.20.10\n  Git commit:       311b9ff\n  Built:            Thu Oct 26 09:08:11 2023\n  OS/Arch:          linux/amd64\n  Experimental:     false\n containerd:\n  Version:          1.6.25\n  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n  Version:          1.1.10\n  GitCommit:        v1.1.10-0-g18a0cb0\n docker-init:\n  Version:          0.19.0\n  GitCommit:        de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2025-10-19 00:20:09.997970', 'end': '2025-10-19 00:20:10.046584', 'delta': '0:00:00.048614', 'msg': '', 'zuul_log_id': 'fa163ec2-ffbe-3265-5e2a-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version:           24.0.7', ' API version:       1.43', ' Go version:        go1.20.10', ' Git commit:        afdd53b', ' Built:             Thu Oct 26 09:09:39 2023', ' OS/Arch:           linux/amd64', ' Context:           default', '', 'Server: Docker Engine - Community', ' Engine:', '  Version:          24.0.7', '  API version:      1.43 (minimum version 1.12)', '  Go version:       go1.20.10', '  Git commit:       311b9ff', '  Built:            Thu Oct 26 09:08:11 2023', '  OS/Arch:          linux/amd64', '  Experimental:     false', ' containerd:', '  Version:          1.6.25', '  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', '  Version:          1.1.10', '  GitCommit:        v1.1.10-0-g18a0cb0', ' docker-init:', '  Version:          0.19.0', '  GitCommit:        de40ad0'], 'stderr_lines': [], 'failed': False}
2025-10-19 00:20:10.308100 | 
2025-10-19 00:20:10.308215 | TASK [Use buildset registry]
2025-10-19 00:20:10.324539 | controller | skipping: Conditional result was False
2025-10-19 00:20:10.338372 | 
2025-10-19 00:20:10.338508 | TASK [Ensure python3.8 is present]
2025-10-19 00:20:10.376084 | controller | skipping: Conditional result was False
2025-10-19 00:20:10.387008 | 
2025-10-19 00:20:10.387270 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-10-19 00:20:10.413567 | controller | ok
2025-10-19 00:20:10.445826 | 
2025-10-19 00:20:10.446125 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-10-19 00:20:18.433708 | controller | ok: Nothing to do
2025-10-19 00:20:18.440367 | 
2025-10-19 00:20:18.440506 | TASK [our-ensure-python : Also install python3-devel]
2025-10-19 00:20:33.180276 | controller | changed
2025-10-19 00:20:33.207482 | 
2025-10-19 00:20:33.207599 | TASK [Run ensure-virtualenv role]
2025-10-19 00:20:33.231786 | controller | ok
2025-10-19 00:20:33.266552 | 
2025-10-19 00:20:33.266697 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-10-19 00:20:33.995110 | controller | /usr/bin/virtualenv
2025-10-19 00:20:34.123377 | controller | ok: Runtime: 0:00:00.007891
2025-10-19 00:20:34.134077 | 
2025-10-19 00:20:34.134232 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-10-19 00:20:34.157895 | controller | skipping: Conditional result was False
2025-10-19 00:20:34.158245 | controller | changed: All items complete
2025-10-19 00:20:34.158277 | 
2025-10-19 00:20:34.173094 | 
2025-10-19 00:20:34.173273 | TASK [Find the full path of the Python interpreter]
2025-10-19 00:20:34.782665 | controller | /usr/bin/python3.8
2025-10-19 00:20:35.037551 | controller | ok
2025-10-19 00:20:35.044798 | 
2025-10-19 00:20:35.044921 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-10-19 00:20:37.785385 | controller | created virtual environment CPython3.8.18.final.0-64 in 1238ms
2025-10-19 00:20:37.843722 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-10-19 00:20:37.843785 | controller |   seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv)
2025-10-19 00:20:37.843803 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-10-19 00:20:37.843825 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-10-19 00:20:38.453175 | controller | changed
2025-10-19 00:20:38.476561 | 
2025-10-19 00:20:38.476869 | TASK [Set selinux package]
2025-10-19 00:20:38.508927 | controller | ok
2025-10-19 00:20:38.522600 | 
2025-10-19 00:20:38.522817 | TASK [Set selinux package (Fedora)]
2025-10-19 00:20:38.558962 | controller | ok
2025-10-19 00:20:38.566897 | 
2025-10-19 00:20:38.567090 | TASK [Install selinux into virtualenv]
2025-10-19 00:20:45.219027 | controller | Collecting selinux-please-lie-to-me
2025-10-19 00:20:45.285348 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-10-19 00:20:45.916565 | controller | Collecting setuptools<50.0.0
2025-10-19 00:20:45.948492 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-10-19 00:20:46.011510 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 14.5 MB/s eta 0:00:00
2025-10-19 00:20:46.141020 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-10-19 00:20:46.141429 | controller |   Attempting uninstall: setuptools
2025-10-19 00:20:46.143722 | controller |     Found existing installation: setuptools 62.6.0
2025-10-19 00:20:46.287591 | controller |     Uninstalling setuptools-62.6.0:
2025-10-19 00:20:46.304898 | controller |       Successfully uninstalled setuptools-62.6.0
2025-10-19 00:20:47.119051 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-10-19 00:20:47.312622 | controller |
2025-10-19 00:20:47.636835 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-10-19 00:20:47.636937 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-10-19 00:20:47.948417 | controller | ok: Runtime: 0:00:07.830333
2025-10-19 00:20:47.956602 | 
2025-10-19 00:20:47.956704 | TASK [Install pytest-forked into virtualenv]
2025-10-19 00:20:49.452883 | controller | Collecting pytest-forked
2025-10-19 00:20:49.510999 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-10-19 00:20:49.712955 | controller | Collecting pytest>=3.10
2025-10-19 00:20:49.718916 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2025-10-19 00:20:49.759204 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 9.5 MB/s eta 0:00:00
2025-10-19 00:20:49.810392 | controller | Collecting py
2025-10-19 00:20:49.816195 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-10-19 00:20:49.826598 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 13.3 MB/s eta 0:00:00
2025-10-19 00:20:49.899030 | controller | Collecting pluggy<2,>=1.5
2025-10-19 00:20:49.905197 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-10-19 00:20:49.979409 | controller | Collecting packaging
2025-10-19 00:20:49.984951 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-10-19 00:20:49.994210 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 11.1 MB/s eta 0:00:00
2025-10-19 00:20:50.064959 | controller | Collecting tomli>=1
2025-10-19 00:20:50.070375 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2025-10-19 00:20:50.128472 | controller | Collecting exceptiongroup>=1.0.0rc8
2025-10-19 00:20:50.134287 | controller |   Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB)
2025-10-19 00:20:50.171166 | controller | Collecting iniconfig
2025-10-19 00:20:50.176456 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-10-19 00:20:50.260845 | controller | Collecting typing-extensions>=4.6.0
2025-10-19 00:20:50.266743 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2025-10-19 00:20:50.278618 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 4.5 MB/s eta 0:00:00
2025-10-19 00:20:50.375493 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-10-19 00:20:51.186788 | controller | Successfully installed exceptiongroup-1.3.0 iniconfig-2.1.0 packaging-25.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.3.0 typing-extensions-4.13.2
2025-10-19 00:20:51.192366 | controller |
2025-10-19 00:20:51.470803 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-10-19 00:20:51.470848 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-10-19 00:20:51.819697 | controller | ok: Runtime: 0:00:02.745580
2025-10-19 00:20:51.836420 | 
2025-10-19 00:20:51.836626 | TASK [Update pip]
2025-10-19 00:20:54.543571 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2025-10-19 00:20:55.042840 | controller | Collecting pip
2025-10-19 00:20:55.180911 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2025-10-19 00:20:55.304966 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 16.2 MB/s eta 0:00:00
2025-10-19 00:20:55.540805 | controller | Installing collected packages: pip
2025-10-19 00:20:55.541007 | controller |   Attempting uninstall: pip
2025-10-19 00:20:55.542060 | controller |     Found existing installation: pip 22.2.2
2025-10-19 00:20:56.137996 | controller |     Uninstalling pip-22.2.2:
2025-10-19 00:20:56.215394 | controller |       Successfully uninstalled pip-22.2.2
2025-10-19 00:20:58.892426 | controller | Successfully installed pip-25.0.1
2025-10-19 00:20:59.748628 | controller | ok: Runtime: 0:00:06.167911
2025-10-19 00:20:59.761352 | 
2025-10-19 00:20:59.761499 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-10-19 00:21:01.051240 | controller | changed
2025-10-19 00:21:01.057846 | 
2025-10-19 00:21:01.057991 | TASK [Install ansible into virtualenv]
2025-10-19 00:21:02.602134 | controller | Processing ./src/github.com/ansible/ansible
2025-10-19 00:21:02.603897 | controller |   Preparing metadata (setup.py): started
2025-10-19 00:21:04.597433 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-10-19 00:21:04.674753 | controller | Collecting jinja2 (from ansible==2.9.27.post0)
2025-10-19 00:21:04.691302 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-10-19 00:21:04.780357 | controller | Collecting PyYAML (from ansible==2.9.27.post0)
2025-10-19 00:21:04.789417 | controller |   Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.1 kB)
2025-10-19 00:21:05.284643 | controller | Collecting cryptography (from ansible==2.9.27.post0)
2025-10-19 00:21:05.291247 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-10-19 00:21:05.538389 | controller | Collecting cffi>=1.14 (from cryptography->ansible==2.9.27.post0)
2025-10-19 00:21:05.545269 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2025-10-19 00:21:05.554684 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.8/site-packages (from cryptography->ansible==2.9.27.post0) (4.13.2)
2025-10-19 00:21:05.674371 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible==2.9.27.post0)
2025-10-19 00:21:05.681616 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2025-10-19 00:21:05.709199 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible==2.9.27.post0)
2025-10-19 00:21:05.714234 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-10-19 00:21:05.732346 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2025-10-19 00:21:05.788149 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 100.6 MB/s eta 0:00:00
2025-10-19 00:21:05.794935 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-10-19 00:21:05.811981 | controller | Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (806 kB)
2025-10-19 00:21:05.862534 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 16.4 MB/s eta 0:00:00
2025-10-19 00:21:05.867964 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2025-10-19 00:21:05.886067 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2025-10-19 00:21:05.896979 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-10-19 00:21:05.925832 | controller | Building wheels for collected packages: ansible
2025-10-19 00:21:05.926937 | controller |   Building wheel for ansible (setup.py): started
2025-10-19 00:21:16.097663 | controller |   Building wheel for ansible (setup.py): finished with status 'done'
2025-10-19 00:21:16.115937 | controller |   Created wheel for ansible: filename=ansible-2.9.27.post0-py3-none-any.whl size=16207514 sha256=7ef9eb94e99fffe05acb928bccd7437e4936e0c8ebe89515127dda022b65d596
2025-10-19 00:21:16.116232 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-_lki2rcx/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2025-10-19 00:21:16.200389 | controller | Successfully built ansible
2025-10-19 00:21:16.405949 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible
2025-10-19 00:21:29.807419 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-2.9.27.post0 cffi-1.17.1 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23
2025-10-19 00:21:30.435280 | controller | ok: Runtime: 0:00:28.065199
2025-10-19 00:21:30.442223 | 
2025-10-19 00:21:30.442298 | TASK [Setup download-artifact-fork role]
2025-10-19 00:21:30.461805 | controller | ok
2025-10-19 00:21:30.480588 | 
2025-10-19 00:21:30.480672 | TASK [download-artifact-fork : Parse inventory response]
2025-10-19 00:21:30.512820 | controller | ok
2025-10-19 00:21:30.522370 | 
2025-10-19 00:21:30.522521 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-10-19 00:21:30.548116 | controller | skipping: Conditional result was False
2025-10-19 00:21:30.555421 | 
2025-10-19 00:21:30.555552 | TASK [download-artifact-fork : Parse build response]
2025-10-19 00:21:30.580627 | controller | skipping: Conditional result was False
2025-10-19 00:21:30.597378 | 
2025-10-19 00:21:30.597619 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-10-19 00:21:31.388952 | controller | changed
2025-10-19 00:21:31.395447 | 
2025-10-19 00:21:31.395569 | LOOP [download-artifact-fork : Download archive by type]
2025-10-19 00:21:31.419596 | controller | skipping: Conditional result was False
2025-10-19 00:21:31.419910 | 
2025-10-19 00:21:34.068824 | controller | changed: OK (20209 bytes)
2025-10-19 00:21:34.089966 | 
2025-10-19 00:21:34.090180 | TASK [deploy-artifacts : define __collections]
2025-10-19 00:21:34.112939 | controller | ok
2025-10-19 00:21:34.120008 | 
2025-10-19 00:21:34.120161 | LOOP [deploy-artifacts : Create list of collections]
2025-10-19 00:21:34.151995 | controller | skipping: Conditional result was False
2025-10-19 00:21:34.232639 | controller | ok:
2025-10-19 00:21:34.232996 | controller | {
2025-10-19 00:21:34.233059 | controller |   "branch": "main",
2025-10-19 00:21:34.233090 | controller |   "job": "build-ansible-collection",
2025-10-19 00:21:34.233117 | controller |   "metadata": {
2025-10-19 00:21:34.233178 | controller |     "type": "ansible_collection",
2025-10-19 00:21:34.233208 | controller |     "version": "5.0.0"
2025-10-19 00:21:34.233233 | controller |   },
2025-10-19 00:21:34.233258 | controller |   "name": "ansible.network",
2025-10-19 00:21:34.233283 | controller |   "project": "ansible-collections/ansible.network",
2025-10-19 00:21:34.233307 | controller |   "url": "https://91c74dd06a1ab84a2f2f-8137f72a768c09c8b8e80f301504c1de.ssl.cf5.rackcdn.com/ansible/d8287ceb260847e59c5ac14f49f2a1c1/artifacts/ansible-network-5.0.0.tar.gz"
2025-10-19 00:21:34.233341 | controller | }
2025-10-19 00:21:34.253783 | 
2025-10-19 00:21:34.253937 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-10-19 00:21:35.414717 | controller | Process install dependency map
2025-10-19 00:22:23.106089 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\Starting collection install process
2025-10-19 00:22:23.207656 | controller | |Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2025-10-19 00:22:23.207776 | controller | Installing 'ansible.netcommon:8.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2025-10-19 00:22:24.712946 | controller | /-\|/-\|/-\|/-\Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2025-10-19 00:22:26.628967 | controller | |/-\|/-\|/-\|/-\|/-Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2025-10-19 00:22:29.037020 | controller | \|/-\|/-\|/-\|/-\|/-\|/-Installing 'cisco.ios:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2025-10-19 00:22:31.878616 | controller | \|/-\|/-\|/-\|/-\|/-\|/-\|/-Installing 'cisco.iosxr:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2025-10-19 00:22:34.288423 | controller | \|/-\|/-\|/-\|/-\|/-\|/-Installing 'cisco.nxos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2025-10-19 00:22:37.506055 | controller | \|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2025-10-19 00:22:40.163153 | controller | ok: Runtime: 0:01:04.877927
2025-10-19 00:22:40.187448 | 
2025-10-19 00:22:40.187621 | TASK [podman may need iptables kernel modules]
2025-10-19 00:22:41.560949 | controller | ok: Runtime: 0:00:00.010917
2025-10-19 00:22:41.591705 | 
2025-10-19 00:22:41.591833 | PLAY RECAP
2025-10-19 00:22:41.591887 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2025-10-19 00:22:41.591914 | 
2025-10-19 00:22:41.756382 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-10-19 00:22:41.757446 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-10-19 00:22:42.428162 | 
2025-10-19 00:22:42.428314 | PLAY [controller]
2025-10-19 00:22:42.453330 | 
2025-10-19 00:22:42.453488 | TASK [Copy the galaxy.yml on the controller]
2025-10-19 00:22:47.241243 | controller | changed
2025-10-19 00:22:47.249614 | 
2025-10-19 00:22:47.249796 | TASK [Load information from galaxy.yml]
2025-10-19 00:22:47.283571 | controller | ok
2025-10-19 00:22:47.291651 | 
2025-10-19 00:22:47.291777 | LOOP [Enable FIPS mode]
2025-10-19 00:22:47.319042 | 
2025-10-19 00:22:47.319245 | LOOP [ansible-test : Include branch specific variables.]
2025-10-19 00:22:47.366109 | controller | ok: "/var/lib/zuul/builds/38eb9dee3f0d4f1f94732218991f0a7a/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2025-10-19 00:22:47.366385 | 
2025-10-19 00:22:47.387912 | 
2025-10-19 00:22:47.388106 | TASK [ansible-test : Setup --skip-tags for test_options]
2025-10-19 00:22:47.444251 | controller | ok
2025-10-19 00:22:47.455367 | 
2025-10-19 00:22:47.455522 | TASK [ansible-test : Enable --inventory for network-integration]
2025-10-19 00:22:47.482008 | controller | skipping: Conditional result was False
2025-10-19 00:22:47.493462 | 
2025-10-19 00:22:47.493619 | TASK [ansible-test : Enable --retry-on-error]
2025-10-19 00:22:47.520746 | controller | skipping: Conditional result was False
2025-10-19 00:22:47.531206 | 
2025-10-19 00:22:47.531360 | TASK [ansible-test : Enable --continue-on-error]
2025-10-19 00:22:47.578985 | controller | ok
2025-10-19 00:22:47.589663 | 
2025-10-19 00:22:47.590203 | TASK [ansible-test : Enable --requirements]
2025-10-19 00:22:47.616169 | controller | skipping: Conditional result was False
2025-10-19 00:22:47.629535 | 
2025-10-19 00:22:47.629700 | TASK [ansible-test : Adjust options for unit tests]
2025-10-19 00:22:47.656228 | controller | skipping: Conditional result was False
2025-10-19 00:22:47.703822 | 
2025-10-19 00:22:47.703982 | TASK [ansible-test : Install requirements with unit tests]
2025-10-19 00:22:47.735859 | controller | ok
2025-10-19 00:22:47.742157 | 
2025-10-19 00:22:47.742250 | TASK [ansible-test : Set --skip-test]
2025-10-19 00:22:47.798360 | controller | skipping: Conditional result was False
2025-10-19 00:22:47.809450 | 
2025-10-19 00:22:47.809597 | TASK [ansible-test : Reduce verbosity]
2025-10-19 00:22:47.846439 | controller | ok
2025-10-19 00:22:47.857039 | 
2025-10-19 00:22:47.857214 | TASK [ansible-test : Setup --python option]
2025-10-19 00:22:47.875984 | controller | skipping: Conditional result was False
2025-10-19 00:22:47.889293 | 
2025-10-19 00:22:47.889445 | TASK [ansible-test : Setup --docker option]
2025-10-19 00:22:47.925890 | controller | ok
2025-10-19 00:22:47.935248 | 
2025-10-19 00:22:47.935804 | TASK [ansible-test : check if MANIFEST.json exists]
2025-10-19 00:22:49.092085 | controller | ok
2025-10-19 00:22:49.098384 | 
2025-10-19 00:22:49.098480 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2025-10-19 00:22:49.114806 | controller | skipping: Conditional result was False
2025-10-19 00:22:49.126613 | 
2025-10-19 00:22:49.126786 | TASK [ansible-test : Load information from MANIFEST.json]
2025-10-19 00:22:49.143477 | controller | skipping: Conditional result was False
2025-10-19 00:22:49.153661 | 
2025-10-19 00:22:49.153793 | TASK [ansible-test : Setup location of project for integration tests]
2025-10-19 00:22:49.169223 | controller | skipping: Conditional result was False
2025-10-19 00:22:49.178081 | 
2025-10-19 00:22:49.178224 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2025-10-19 00:22:50.381760 | controller | ok
2025-10-19 00:22:50.395523 | 
2025-10-19 00:22:50.395685 | TASK [ansible-test : Load information from galaxy.yml]
2025-10-19 00:22:50.433064 | controller | ok
2025-10-19 00:22:50.443938 | 
2025-10-19 00:22:50.444128 | TASK [ansible-test : Setup location of project for integration tests]
2025-10-19 00:22:50.489812 | controller | ok
2025-10-19 00:22:50.499735 | 
2025-10-19 00:22:50.499854 | TASK [ansible-test : Setup minimum test requirements]
2025-10-19 00:22:50.535454 | controller | ok
2025-10-19 00:22:50.544601 | 
2025-10-19 00:22:50.544831 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2025-10-19 00:22:51.395552 | controller | ok
2025-10-19 00:22:51.410586 | 
2025-10-19 00:22:51.410754 | TASK [ansible-test : Install python requirements]
2025-10-19 00:22:52.848996 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-10-19 00:22:52.865557 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2025-10-19 00:22:52.900471 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-10-19 00:22:52.905050 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2025-10-19 00:22:52.915681 | controller | Requirement already satisfied: pyyaml in ./venv/lib64/python3.8/site-packages (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1)) (6.0.3)
2025-10-19 00:22:52.923429 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2025-10-19 00:22:52.937281 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2025-10-19 00:22:53.001734 | controller | Installing collected packages: pathspec, yamllint
2025-10-19 00:22:53.105457 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2025-10-19 00:22:53.788676 | controller | ok: Runtime: 0:00:00.978157
2025-10-19 00:22:53.798447 | 
2025-10-19 00:22:53.798605 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2025-10-19 00:22:54.167812 | controller | skipping: Conditional result was False
2025-10-19 00:22:54.182232 | 
2025-10-19 00:22:54.182429 | TASK [ansible-test : Enable persistent connection logging]
2025-10-19 00:22:55.398265 | controller | changed: section and option added
2025-10-19 00:22:55.406788 | 
2025-10-19 00:22:55.406974 | TASK [ansible-test : Set persistent ssh_type value]
2025-10-19 00:22:56.424801 | controller | changed: option added
2025-10-19 00:22:56.435143 | 
2025-10-19 00:22:56.435282 | TASK [ansible-test : Set ansible log path]
2025-10-19 00:22:57.582110 | controller | changed: section and option added
2025-10-19 00:22:57.591073 | 
2025-10-19 00:22:57.591238 | TASK [ansible-test : Identify the changed targets]
2025-10-19 00:22:57.955412 | controller | skipping: Conditional result was False
2025-10-19 00:22:57.966701 | 
2025-10-19 00:22:57.966869 | TASK [ansible-test : set_fact]
2025-10-19 00:22:58.004327 | controller | skipping: Conditional result was False
2025-10-19 00:22:58.018178 | 
2025-10-19 00:22:58.018367 | TASK [ansible-test : Set the targets]
2025-10-19 00:22:58.060113 | controller | ok
2025-10-19 00:22:58.073275 | 
2025-10-19 00:22:58.073449 | TASK [ansible-test : set_fact]
2025-10-19 00:22:58.100182 | controller | skipping: Conditional result was False
2025-10-19 00:22:58.110491 | 
2025-10-19 00:22:58.110618 | TASK [ansible-test : List the targets]
2025-10-19 00:22:58.484329 | controller | skipping: Conditional result was False
2025-10-19 00:22:58.498808 | 
2025-10-19 00:22:58.499046 | TASK [ansible-test : set_fact]
2025-10-19 00:22:58.526477 | controller | skipping: Conditional result was False
2025-10-19 00:22:58.581398 | 
2025-10-19 00:22:58.581547 | TASK [ansible-test : set_fact]
2025-10-19 00:22:58.607114 | controller | skipping: Conditional result was False
2025-10-19 00:22:58.616666 | 
2025-10-19 00:22:58.616795 | LOOP [ansible-test : set_fact]
2025-10-19 00:22:58.681562 | 
2025-10-19 00:22:58.681770 | TASK [ansible-test : set_fact]
2025-10-19 00:22:58.697280 | controller | skipping: Conditional result was False
2025-10-19 00:22:58.705502 | 
2025-10-19 00:22:58.705627 | TASK [ansible-test : debug]
2025-10-19 00:22:58.720710 | controller | skipping: Conditional result was False
2025-10-19 00:22:58.727927 | 
2025-10-19 00:22:58.728048 | TASK [ansible-test : debug]
2025-10-19 00:22:58.760197 | About to run: ansible-test sanity --requirements --docker -v
2025-10-19 00:22:58.768731 | 
2025-10-19 00:22:58.768864 | TASK [ansible-test : Run the test suite]
2025-10-19 00:23:00.008252 | controller | Run command: docker images quay.io/ansible/default-test-container:1.10.1 --format '{{json .}}'
2025-10-19 00:23:00.067751 | controller | Run command: docker pull quay.io/ansible/default-test-container:1.10.1
2025-10-19 00:23:01.896903 | controller | 1.10.1: Pulling from ansible/default-test-container
2025-10-19 00:23:01.896959 | controller | [DEPRECATION NOTICE] Docker Image Format v1, and Docker Image manifest version 2, schema 1 support will be removed in an upcoming release. Suggest the author of quay.io/ansible/default-test-container:1.10.1 to upgrade the image to the OCI Format, or Docker Image manifest v2, schema 2. More information at https://docs.docker.com/go/deprecated-image-specs/
2025-10-19 00:23:01.896981 | controller | 5667fdb72017: Pulling fs layer
2025-10-19 00:23:01.896988 | controller | d83811f270d5: Pulling fs layer
2025-10-19 00:23:01.896994 | controller | ee671aafb583: Pulling fs layer
2025-10-19 00:23:01.897003 | controller | 7fc152dfb3a6: Pulling fs layer
2025-10-19 00:23:01.897012 | controller | 3fb967a6fd03: Pulling fs layer
2025-10-19 00:23:01.897018 | controller | b3e63023ca5f: Pulling fs layer
2025-10-19 00:23:01.897024 | controller | 653cb3e65dbf: Pulling fs layer
2025-10-19 00:23:01.897038 | controller | f4712b586375: Pulling fs layer
2025-10-19 00:23:01.897047 | controller | d696ed7812f8: Pulling fs layer
2025-10-19 00:23:01.897092 | controller | 29bf28764f14: Pulling fs layer
2025-10-19 00:23:01.897101 | controller | 47d7edb3c2b7: Pulling fs layer
2025-10-19 00:23:01.897107 | controller | 07550ad4f6e4: Pulling fs layer
2025-10-19 00:23:01.897113 | controller | 7e79526e64c5: Pulling fs layer
2025-10-19 00:23:01.897119 | controller | 4fffb32882b7: Pulling fs layer
2025-10-19 00:23:01.897126 | controller | fc7a38b06f4e: Pulling fs layer
2025-10-19 00:23:01.897132 | controller | 6d9d912e060e: Pulling fs layer
2025-10-19 00:23:01.897138 | controller | 5dd542dd28d9: Pulling fs layer
2025-10-19 00:23:01.897144 | controller | cef8beb99552: Pulling fs layer
2025-10-19 00:23:01.897151 | controller | bd5557e101ad: Pulling fs layer
2025-10-19 00:23:01.897156 | controller | e057c20fabdd: Pulling fs layer
2025-10-19 00:23:01.897164 | controller | d16059cdae2e: Pulling fs layer
2025-10-19 00:23:01.897169 | controller | 431e42d906a3: Pulling fs layer
2025-10-19 00:23:01.897175 | controller | 7a0fd3b1d7c3: Pulling fs layer
2025-10-19 00:23:01.897181 | controller | aacdb6db0c8c: Pulling fs layer
2025-10-19 00:23:01.897187 | controller | 2e7f04ae8c8b: Pulling fs layer
2025-10-19 00:23:01.897192 | controller | 4cd343027a44: Pulling fs layer
2025-10-19 00:23:01.897199 | controller | 0b4379883f90: Pulling fs layer
2025-10-19 00:23:01.897204 | controller | 3d9d6d9dfc91: Pulling fs layer
2025-10-19 00:23:01.897213 | controller | 57d6314bd293: Pulling fs layer
2025-10-19 00:23:01.897222 | controller | 774036afeb7f: Pulling fs layer
2025-10-19 00:23:01.897228 | controller | 54b10f0e1645: Pulling fs layer
2025-10-19 00:23:01.897233 | controller | 78cf439d9fdb: Pulling fs layer
2025-10-19 00:23:01.897239 | controller | 36e5e20969c7: Pulling fs layer
2025-10-19 00:23:01.897244 | controller | 4b19ac3f17a8: Pulling fs layer
2025-10-19 00:23:01.897250 | controller | bd5557e101ad: Waiting
2025-10-19 00:23:01.897255 | controller | e057c20fabdd: Waiting
2025-10-19 00:23:01.897263 | controller | d16059cdae2e: Waiting
2025-10-19 00:23:01.897269 | controller | 431e42d906a3: Waiting
2025-10-19 00:23:01.897275 | controller | 7a0fd3b1d7c3: Waiting
2025-10-19 00:23:01.897280 | controller | aacdb6db0c8c: Waiting
2025-10-19 00:23:01.897286 | controller | 2e7f04ae8c8b: Waiting
2025-10-19 00:23:01.897292 | controller | 4cd343027a44: Waiting
2025-10-19 00:23:01.897297 | controller | 0b4379883f90: Waiting
2025-10-19 00:23:01.897303 | controller | 3d9d6d9dfc91: Waiting
2025-10-19 00:23:01.897309 | controller | 57d6314bd293: Waiting
2025-10-19 00:23:01.897314 | controller | 774036afeb7f: Waiting
2025-10-19 00:23:01.897320 | controller | 54b10f0e1645: Waiting
2025-10-19 00:23:01.897325 | controller | 78cf439d9fdb: Waiting
2025-10-19 00:23:01.897334 | controller | 36e5e20969c7: Waiting
2025-10-19 00:23:01.897339 | controller | 4b19ac3f17a8: Waiting
2025-10-19 00:23:01.897352 | controller | 7fc152dfb3a6: Waiting
2025-10-19 00:23:03.099660 | controller | 3fb967a6fd03: Waiting
2025-10-19 00:23:03.099701 | controller | b3e63023ca5f: Waiting
2025-10-19 00:23:03.099708 | controller | 653cb3e65dbf: Waiting
2025-10-19 00:23:03.099714 | controller | f4712b586375: Waiting
2025-10-19 00:23:03.099720 | controller | d696ed7812f8: Waiting
2025-10-19 00:23:03.099726 | controller | 29bf28764f14: Waiting
2025-10-19 00:23:03.099733 | controller | 47d7edb3c2b7: Waiting
2025-10-19 00:23:03.099757 | controller | 07550ad4f6e4: Waiting
2025-10-19 00:23:03.099766 | controller | 7e79526e64c5: Waiting
2025-10-19 00:23:03.099775 | controller | 4fffb32882b7: Waiting
2025-10-19 00:23:03.099825 | controller | fc7a38b06f4e: Waiting
2025-10-19 00:23:03.099835 | controller | 6d9d912e060e: Waiting
2025-10-19 00:23:03.099841 | controller | 5dd542dd28d9: Waiting
2025-10-19 00:23:03.099847 | controller | cef8beb99552: Waiting
2025-10-19 00:23:03.099862 | controller | d83811f270d5: Verifying Checksum
2025-10-19 00:23:03.212308 | controller | d83811f270d5: Download complete
2025-10-19 00:23:03.212357 | controller | ee671aafb583: Download complete
2025-10-19 00:23:03.941349 | controller | 7fc152dfb3a6: Verifying Checksum
2025-10-19 00:23:04.921350 | controller | 7fc152dfb3a6: Download complete
2025-10-19 00:23:04.921401 | controller | b3e63023ca5f: Verifying Checksum
2025-10-19 00:23:05.967973 | controller | b3e63023ca5f: Download complete
2025-10-19 00:23:05.968026 | controller | 653cb3e65dbf: Verifying Checksum
2025-10-19 00:23:09.396783 | controller | 653cb3e65dbf: Download complete
2025-10-19 00:23:09.396840 | controller | 5667fdb72017: Verifying Checksum
2025-10-19 00:23:10.502729 | controller | 5667fdb72017: Download complete
2025-10-19 00:23:10.502792 | controller | d696ed7812f8: Verifying Checksum
2025-10-19 00:23:11.083937 | controller | d696ed7812f8: Download complete
2025-10-19 00:23:11.083990 | controller | 3fb967a6fd03: Verifying Checksum
2025-10-19 00:23:11.595354 | controller | 3fb967a6fd03: Download complete
2025-10-19 00:23:11.595421 | controller | 29bf28764f14: Verifying Checksum
2025-10-19 00:23:11.624201 | controller | 29bf28764f14: Download complete
2025-10-19 00:23:11.624258 | controller | 5667fdb72017: Pull complete
2025-10-19 00:23:11.660819 | controller | d83811f270d5: Pull complete
2025-10-19 00:23:11.690051 | controller | ee671aafb583: Pull complete
2025-10-19 00:23:11.711429 | controller | 7fc152dfb3a6: Pull complete
2025-10-19 00:23:12.552055 | controller | 3fb967a6fd03: Pull complete
2025-10-19 00:23:12.585744 | controller | b3e63023ca5f: Pull complete
2025-10-19 00:23:12.613870 | controller | 653cb3e65dbf: Pull complete
2025-10-19 00:23:12.699705 | controller | 07550ad4f6e4: Verifying Checksum
2025-10-19 00:23:12.699825 | controller | 07550ad4f6e4: Download complete
2025-10-19 00:23:12.858526 | controller | 47d7edb3c2b7: Download complete
2025-10-19 00:23:13.817802 | controller | 7e79526e64c5: Verifying Checksum
2025-10-19 00:23:13.817882 | controller | 7e79526e64c5: Download complete
2025-10-19 00:23:13.861300 | controller | 4fffb32882b7: Verifying Checksum
2025-10-19 00:23:13.861411 | controller | 4fffb32882b7: Download complete
2025-10-19 00:23:14.802014 | controller | 6d9d912e060e: Verifying Checksum
2025-10-19 00:23:14.802500 | controller | 6d9d912e060e: Download complete
2025-10-19 00:23:15.592997 | controller | fc7a38b06f4e: Verifying Checksum
2025-10-19 00:23:15.593164 | controller | fc7a38b06f4e: Download complete
2025-10-19 00:23:15.940627 | controller | 5dd542dd28d9: Verifying Checksum
2025-10-19 00:23:15.940742 | controller | 5dd542dd28d9: Download complete
2025-10-19 00:23:16.764137 | controller | bd5557e101ad: Verifying Checksum
2025-10-19 00:23:16.764291 | controller | bd5557e101ad: Download complete
2025-10-19 00:23:17.674626 | controller | e057c20fabdd: Verifying Checksum
2025-10-19 00:23:17.674786 | controller | e057c20fabdd: Download complete
2025-10-19 00:23:18.651631 | controller | d16059cdae2e: Verifying Checksum
2025-10-19 00:23:18.651738 | controller | d16059cdae2e: Download complete
2025-10-19 00:23:20.729491 | controller | 431e42d906a3: Verifying Checksum
2025-10-19 00:23:20.729597 | controller | 431e42d906a3: Download complete
2025-10-19 00:23:22.436261 | controller | 7a0fd3b1d7c3: Verifying Checksum
2025-10-19 00:23:22.436445 | controller | 7a0fd3b1d7c3: Download complete
2025-10-19 00:23:24.383578 | controller | aacdb6db0c8c: Verifying Checksum
2025-10-19 00:23:24.383801 | controller | aacdb6db0c8c: Download complete
2025-10-19 00:23:25.523188 | controller | 2e7f04ae8c8b: Verifying Checksum
2025-10-19 00:23:25.523377 | controller | 2e7f04ae8c8b: Download complete
2025-10-19 00:23:26.510334 | controller | 4cd343027a44: Verifying Checksum
2025-10-19 00:23:26.510451 | controller | 4cd343027a44: Download complete
2025-10-19 00:23:27.627450 | controller | 0b4379883f90: Download complete
2025-10-19 00:23:28.600128 | controller | 3d9d6d9dfc91: Verifying Checksum
2025-10-19 00:23:28.600402 | controller | 3d9d6d9dfc91: Download complete
2025-10-19 00:23:35.059923 | controller | f4712b586375: Verifying Checksum
2025-10-19 00:23:40.558777 | controller | f4712b586375: Download complete
2025-10-19 00:23:40.558836 | controller | 57d6314bd293: Verifying Checksum
2025-10-19 00:23:49.797253 | controller | 57d6314bd293: Download complete
2025-10-19 00:23:49.797400 | controller | cef8beb99552: Verifying Checksum
2025-10-19 00:23:49.797474 | controller | cef8beb99552: Download complete
2025-10-19 00:23:52.471849 | controller | 54b10f0e1645: Verifying Checksum
2025-10-19 00:23:52.471930 | controller | 54b10f0e1645: Download complete
2025-10-19 00:23:55.942244 | controller | 774036afeb7f: Verifying Checksum
2025-10-19 00:23:55.942699 | controller | 774036afeb7f: Download complete
2025-10-19 00:23:57.297110 | controller | f4712b586375: Pull complete
2025-10-19 00:23:57.332129 | controller | d696ed7812f8: Pull complete
2025-10-19 00:23:57.373794 | controller | 29bf28764f14: Pull complete
2025-10-19 00:23:57.425235 | controller | 47d7edb3c2b7: Pull complete
2025-10-19 00:23:57.452803 | controller | 07550ad4f6e4: Pull complete
2025-10-19 00:23:57.488913 | controller | 7e79526e64c5: Pull complete
2025-10-19 00:23:57.520949 | controller | 4fffb32882b7: Pull complete
2025-10-19 00:23:57.600427 | controller | fc7a38b06f4e: Pull complete
2025-10-19 00:23:57.628530 | controller | 6d9d912e060e: Pull complete
2025-10-19 00:23:57.673498 | controller | 5dd542dd28d9: Pull complete
2025-10-19 00:24:07.126986 | controller | 78cf439d9fdb: Verifying Checksum
2025-10-19 00:24:07.127059 | controller | 78cf439d9fdb: Download complete
2025-10-19 00:24:08.411184 | controller | 36e5e20969c7: Verifying Checksum
2025-10-19 00:24:08.411964 | controller | 36e5e20969c7: Download complete
2025-10-19 00:24:11.175094 | controller | 4b19ac3f17a8: Verifying Checksum
2025-10-19 00:24:19.385047 | controller | 4b19ac3f17a8: Download complete
2025-10-19 00:24:19.386285 | controller | cef8beb99552: Pull complete
2025-10-19 00:24:19.411435 | controller | bd5557e101ad: Pull complete
2025-10-19 00:24:19.448406 | controller | e057c20fabdd: Pull complete
2025-10-19 00:24:19.498564 | controller | d16059cdae2e: Pull complete
2025-10-19 00:24:21.617436 | controller | 431e42d906a3: Pull complete
2025-10-19 00:24:21.669954 | controller | 7a0fd3b1d7c3: Pull complete
2025-10-19 00:24:21.712870 | controller | aacdb6db0c8c: Pull complete
2025-10-19 00:24:21.750556 | controller | 2e7f04ae8c8b: Pull complete
2025-10-19 00:24:21.792670 | controller | 4cd343027a44: Pull complete
2025-10-19 00:24:21.842432 | controller | 0b4379883f90: Pull complete
2025-10-19 00:24:21.884533 | controller | 3d9d6d9dfc91: Pull complete
2025-10-19 00:24:26.951727 | controller | 57d6314bd293: Pull complete
2025-10-19 00:24:47.739840 | controller | 774036afeb7f: Pull complete
2025-10-19 00:25:17.421843 | controller | 54b10f0e1645: Pull complete
2025-10-19 00:25:46.206379 | controller | 78cf439d9fdb: Pull complete
2025-10-19 00:26:13.927953 | controller | 36e5e20969c7: Pull complete
2025-10-19 00:26:45.732556 | controller | 4b19ac3f17a8: Pull complete
2025-10-19 00:26:45.760892 | controller | Digest: sha256:954c4edffb0905df64e23c8611b1c4a62db9af13062f51cad5b658e727238d41
2025-10-19 00:26:45.766800 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:1.10.1
2025-10-19 00:26:45.778473 | controller | quay.io/ansible/default-test-container:1.10.1
2025-10-19 00:26:45.783802 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2025-10-19 00:26:45.824722 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2025-10-19 00:26:47.264313 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2025-10-19 00:26:47.264549 | controller | 04a5f4cda3ee: Pulling fs layer
2025-10-19 00:26:47.264567 | controller | ff496a88c8ed: Pulling fs layer
2025-10-19 00:26:47.264577 | controller | 0ce83f459fe7: Pulling fs layer
2025-10-19 00:26:47.264584 | controller | 2e5170e1f099: Pulling fs layer
2025-10-19 00:26:47.264590 | controller | 7641eb41b08c: Pulling fs layer
2025-10-19 00:26:47.264599 | controller | ad15fa9da398: Pulling fs layer
2025-10-19 00:26:47.264696 | controller | 087d91352424: Pulling fs layer
2025-10-19 00:26:47.264713 | controller | 8b92efd6a100: Pulling fs layer
2025-10-19 00:26:47.265826 | controller | 2e5170e1f099: Waiting
2025-10-19 00:26:47.265892 | controller | 7641eb41b08c: Waiting
2025-10-19 00:26:47.265985 | controller | ad15fa9da398: Waiting
2025-10-19 00:26:47.266055 | controller | 087d91352424: Waiting
2025-10-19 00:26:47.266155 | controller | 8b92efd6a100: Waiting
2025-10-19 00:26:47.644229 | controller | 0ce83f459fe7: Verifying Checksum
2025-10-19 00:26:47.651168 | controller | 0ce83f459fe7: Download complete
2025-10-19 00:26:47.651276 | controller | ff496a88c8ed: Verifying Checksum
2025-10-19 00:26:48.391408 | controller | ff496a88c8ed: Download complete
2025-10-19 00:26:48.391526 | controller | 7641eb41b08c: Verifying Checksum
2025-10-19 00:26:49.169415 | controller | 7641eb41b08c: Download complete
2025-10-19 00:26:49.169511 | controller | ad15fa9da398: Verifying Checksum
2025-10-19 00:26:49.611693 | controller | ad15fa9da398: Download complete
2025-10-19 00:26:49.611801 | controller | 2e5170e1f099: Verifying Checksum
2025-10-19 00:26:50.318582 | controller | 2e5170e1f099: Download complete
2025-10-19 00:26:50.318685 | controller | 8b92efd6a100: Verifying Checksum
2025-10-19 00:26:50.540373 | controller | 8b92efd6a100: Download complete
2025-10-19 00:26:50.540502 | controller | 04a5f4cda3ee: Verifying Checksum
2025-10-19 00:26:50.775665 | controller | 04a5f4cda3ee: Download complete
2025-10-19 00:26:50.775784 | controller | 087d91352424: Verifying Checksum
2025-10-19 00:26:52.782003 | controller | 087d91352424: Download complete
2025-10-19 00:26:52.782101 | controller | 04a5f4cda3ee: Pull complete
2025-10-19 00:26:52.818820 | controller | ff496a88c8ed: Pull complete
2025-10-19 00:26:52.867995 | controller | 0ce83f459fe7: Pull complete
2025-10-19 00:26:54.471727 | controller | 2e5170e1f099: Pull complete
2025-10-19 00:26:54.512981 | controller | 7641eb41b08c: Pull complete
2025-10-19 00:26:54.553743 | controller | ad15fa9da398: Pull complete
2025-10-19 00:26:55.568870 | controller | 087d91352424: Pull complete
2025-10-19 00:26:55.605278 | controller | 8b92efd6a100: Pull complete
2025-10-19 00:26:55.640875 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2025-10-19 00:26:55.647822 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2025-10-19 00:26:55.658913 | controller | quay.io/ansible/pypi-test-container:1.0.0
2025-10-19 00:26:55.663129 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2025-10-19 00:26:58.953829 | controller | Run command: docker inspect f8db3ada86e1868f27ceb852c37b0ee771592ca55d5c0d92e906e19a6db5456f
2025-10-19 00:26:59.166492 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2025-10-19 00:26:59.181625 | controller | Including collection: ansible.netcommon (288 files)
2025-10-19 00:26:59.195922 | controller | Including collection: ansible.network (23 files)
2025-10-19 00:26:59.195991 | controller | Including collection: ansible.utils (442 files)
2025-10-19 00:26:59.233694 | controller | Including collection: arista.eos (1022 files)
2025-10-19 00:26:59.278222 | controller | Including collection: cisco.ios (1172 files)
2025-10-19 00:26:59.329286 | controller | Including collection: cisco.iosxr (1037 files)
2025-10-19 00:26:59.417108 | controller | Including collection: cisco.nxos (1680 files)
2025-10-19 00:26:59.458187 | controller | Including collection: junipernetworks.junos (963 files)
2025-10-19 00:26:59.507876 | controller | Creating a payload archive containing 8364 files...
2025-10-19 00:27:02.026127 | controller | Created a 6868949 byte payload archive containing 8364 files in 2 seconds.
2025-10-19 00:27:02.028989 | controller | Assuming Docker is available on localhost.
2025-10-19 00:27:02.029405 | controller | Run command: docker run --detach --volume /sys/fs/cgroup:/sys/fs/cgroup:ro --privileged=false --security-opt seccomp=unconfined --volume /var/run/docker.sock:/var/run/docker.sock quay.io/ansible/default-test-container:1.10.1
2025-10-19 00:27:02.812590 | controller | Run command: docker exec -i 64962dea3a0034eaf82025523ae163424c0279799b817b93adcec2c1e65295d6 dd of=/root/docker.sh bs=65536
2025-10-19 00:27:02.934747 | controller | Run command: docker exec 64962dea3a0034eaf82025523ae163424c0279799b817b93adcec2c1e65295d6 /bin/bash /root/docker.sh
2025-10-19 00:27:03.054859 | controller | Run command: docker exec -i 64962dea3a0034eaf82025523ae163424c0279799b817b93adcec2c1e65295d6 dd of=/root/ansible.tgz bs=65536
2025-10-19 00:27:03.188501 | controller | Run command: docker exec 64962dea3a0034eaf82025523ae163424c0279799b817b93adcec2c1e65295d6 mkdir /root/ansible
2025-10-19 00:27:03.276720 | controller | Run command: docker exec 64962dea3a0034eaf82025523ae163424c0279799b817b93adcec2c1e65295d6 tar oxzf /root/ansible.tgz -C /root/ansible
2025-10-19 00:27:04.487850 | controller | Run command: docker exec 64962dea3a0034eaf82025523ae163424c0279799b817b93adcec2c1e65295d6 /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata metadata-_d3e6viw.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2025-10-19 00:27:05.043369 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2025-10-19 00:27:05.043591 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2025-10-19 00:27:05.044014 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2025-10-19 00:27:05.046154 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-10-19 00:27:05.132783 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2025-10-19 00:27:05.136965 | controller | Read 0 sanity test ignore line(s) for Ansible 2.9 from: tests/sanity/ignore-2.9.txt
2025-10-19 00:27:05.145435 | controller | No tests applicable.
2025-10-19 00:27:05.145569 | controller | Running sanity test 'ansible-doc' with Python 3.6
2025-10-19 00:27:05.145784 | controller | No tests applicable.
2025-10-19 00:27:05.146139 | controller | Run command: /usr/bin/python2.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-10-19 00:27:05.244747 | controller | Running sanity test 'compile' with Python 2.6
2025-10-19 00:27:05.245141 | controller | No tests applicable.
2025-10-19 00:27:05.245622 | controller | Run command: /usr/bin/python2.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-10-19 00:27:05.469442 | controller | Running sanity test 'compile' with Python 2.7
2025-10-19 00:27:05.469696 | controller | No tests applicable.
2025-10-19 00:27:05.469827 | controller | Run command: /usr/bin/python3.5 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-10-19 00:27:05.616856 | controller | Running sanity test 'compile' with Python 3.5
2025-10-19 00:27:05.617190 | controller | No tests applicable.
2025-10-19 00:27:05.617440 | controller | Running sanity test 'compile' with Python 3.6
2025-10-19 00:27:05.617457 | controller | No tests applicable.
2025-10-19 00:27:05.617889 | controller | Run command: /usr/bin/python3.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-10-19 00:27:05.742918 | controller | Running sanity test 'compile' with Python 3.7
2025-10-19 00:27:05.743138 | controller | No tests applicable.
2025-10-19 00:27:05.743589 | controller | Run command: /usr/bin/python3.8 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-10-19 00:27:05.860626 | controller | Running sanity test 'compile' with Python 3.8
2025-10-19 00:27:05.860812 | controller | No tests applicable.
2025-10-19 00:27:05.860895 | controller | Running sanity test 'empty-init' with Python 3.6
2025-10-19 00:27:05.861178 | controller | No tests applicable.
2025-10-19 00:27:05.861247 | controller | Running sanity test 'future-import-boilerplate' with Python 3.6
2025-10-19 00:27:05.861462 | controller | No tests applicable.
2025-10-19 00:27:05.861534 | controller | Running sanity test 'ignores'
2025-10-19 00:27:05.863266 | controller | Run command: /usr/bin/python3.6 -m pip.__main__ install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt
2025-10-19 00:27:06.714195 | controller | Ignoring packaging: markers 'python_version < "3.6"' don't match your environment
2025-10-19 00:27:06.720185 | controller | Ignoring coverage: markers 'python_version > "3.7"' don't match your environment
2025-10-19 00:27:06.721738 | controller | Ignoring cryptography: markers 'python_version < "2.7"' don't match your environment
2025-10-19 00:27:06.725894 | controller | Ignoring deepdiff: markers 'python_version < "3"' don't match your environment
2025-10-19 00:27:06.727555 | controller | Ignoring jinja2: markers 'python_version < "2.7"' don't match your environment
2025-10-19 00:27:06.728955 | controller | Ignoring urllib3: markers 'python_version < "2.7"' don't match your environment
2025-10-19 00:27:06.732329 | controller | Ignoring sphinx: markers 'python_version < "2.7"' don't match your environment
2025-10-19 00:27:06.738372 | controller | Ignoring wheel: markers 'python_version < "2.7"' don't match your environment
2025-10-19 00:27:06.739902 | controller | Ignoring yamllint: markers 'python_version < "2.7"' don't match your environment
2025-10-19 00:27:06.744373 | controller | Ignoring paramiko: markers 'python_version < "2.7"' don't match your environment
2025-10-19 00:27:06.746918 | controller | Ignoring pytest: markers 'python_version < "2.7"' don't match your environment
2025-10-19 00:27:06.748522 | controller | Ignoring pytest: markers 'python_version == "2.7"' don't match your environment
2025-10-19 00:27:06.749928 | controller | Ignoring pytest-forked: markers 'python_version < "2.7"' don't match your environment
2025-10-19 00:27:06.755240 | controller | Ignoring requests: markers 'python_version < "2.7"' don't match your environment
2025-10-19 00:27:06.763053 | controller | Ignoring virtualenv: markers 'python_version < "2.7"' don't match your environment
2025-10-19 00:27:06.765046 | controller | Ignoring pathspec: markers 'python_version < "2.7"' don't match your environment
2025-10-19 00:27:06.766424 | controller | Ignoring pyopenssl: markers 'python_version < "2.7"' don't match your environment
2025-10-19 00:27:06.768910 | controller | Ignoring pyyaml: markers 'python_version < "2.7"' don't match your environment
2025-10-19 00:27:06.770436 | controller | Ignoring pycparser: markers 'python_version < "2.7"' don't match your environment
2025-10-19 00:27:06.773708 | controller | Ignoring xmltodict: markers 'python_version < "2.7"' don't match your environment
2025-10-19 00:27:06.777121 | controller | Ignoring lxml: markers 'python_version < "2.7"' don't match your environment
2025-10-19 00:27:06.778891 | controller | Ignoring pyvmomi: markers 'python_version < "2.7"' don't match your environment
2025-10-19 00:27:06.781208 | controller | Ignoring MarkupSafe: markers 'python_version < "3.6"' don't match your environment
2025-10-19 00:27:06.787186 | controller | Ignoring setuptools: markers 'python_version <= "2.7"' don't match your environment
2025-10-19 00:27:06.791148 | controller | Ignoring websocket-client: markers 'python_version < "3"' don't match your environment
2025-10-19 00:27:06.792503 | controller | Ignoring certifi: markers 'python_version < "3"' don't match your environment
2025-10-19 00:27:06.812863 | controller | Looking in indexes: http://172.17.0.2:3141/root/pypi/+simple/
2025-10-19 00:27:06.814066 | controller | Requirement already satisfied: cryptography<3.4 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (2.7)
2025-10-19 00:27:06.840889 | controller | Requirement already satisfied: rstcheck==3.3.1 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 12)) (3.3.1)
2025-10-19 00:27:06.844788 | controller | Requirement already satisfied: voluptuous>=0.11.0 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 30)) (0.11.7)
2025-10-19 00:27:06.848287 | controller | Requirement already satisfied: cffi!=1.14.4 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 49)) (1.13.0)
2025-10-19 00:27:06.850918 | controller | Requirement already satisfied: pylint==2.3.1 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 58)) (2.3.1)
2025-10-19 00:27:06.857560 | controller | Requirement already satisfied: jinja2 in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 2)) (2.10.3)
2025-10-19 00:27:06.861363 | controller | Requirement already satisfied: pycodestyle in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 3)) (2.5.0)
2025-10-19 00:27:06.865591 | controller | Requirement already satisfied: pyyaml in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 5)) (5.1.2)
2025-10-19 00:27:06.867603 | controller | Requirement already satisfied: virtualenv in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 7)) (16.7.5)
2025-10-19 00:27:06.885575 | controller | Requirement already satisfied: yamllint in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 9)) (1.18.0)
2025-10-19 00:27:06.889384 | controller | Requirement already satisfied: asn1crypto>=0.21.0 in /usr/local/lib/python3.6/dist-packages (from cryptography<3.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (1.1.0)
2025-10-19 00:27:06.892019 | controller | Requirement already satisfied: six>=1.4.1 in /usr/local/lib/python3.6/dist-packages (from cryptography<3.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (1.12.0)
2025-10-19 00:27:06.893859 | controller | Requirement already satisfied: docutils>=0.7 in /usr/local/lib/python3.6/dist-packages (from rstcheck==3.3.1->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 12)) (0.15.2)
2025-10-19 00:27:06.896199 | controller | Requirement already satisfied: pycparser in /usr/local/lib/python3.6/dist-packages (from cffi!=1.14.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 49)) (2.19)
2025-10-19 00:27:06.902195 | controller | Collecting isort==4.3.16 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 55))
2025-10-19 00:27:21.925632 | controller |   WARNING: Retrying (Retry(total=4, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/isort/
2025-10-19 00:27:37.444673 | controller |   WARNING: Retrying (Retry(total=3, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/isort/
2025-10-19 00:27:53.448579 | controller |   WARNING: Retrying (Retry(total=2, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/isort/
2025-10-19 00:28:10.458512 | controller |   WARNING: Retrying (Retry(total=1, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/isort/
2025-10-19 00:28:29.478544 | controller |   WARNING: Retrying (Retry(total=0, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/isort/
2025-10-19 00:28:44.489047 | controller |   ERROR: Could not find a version that satisfies the requirement isort==4.3.16 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 55)) (from versions: none)
2025-10-19 00:28:44.490261 | controller | ERROR: No matching distribution found for isort==4.3.16 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 55))
2025-10-19 00:28:44.670401 | controller | ERROR: Command "/usr/bin/python3.6 -m pip.__main__ install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt" returned exit status 1.
2025-10-19 00:28:44.670729 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2025-10-19 00:28:44.671098 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2025-10-19 00:28:44.671296 | controller | Removing custom PyPI hosts entries: /etc/hosts
2025-10-19 00:28:44.727179 | controller | Run command: docker exec 64962dea3a0034eaf82025523ae163424c0279799b817b93adcec2c1e65295d6 tar czf /root/output.tgz --exclude .tmp -C /root/ansible/ansible_collections/ansible/network/tests output
2025-10-19 00:28:44.811475 | controller | Run command: docker exec -i 64962dea3a0034eaf82025523ae163424c0279799b817b93adcec2c1e65295d6 dd if=/root/output.tgz bs=65536
2025-10-19 00:28:44.887221 | controller | Run command: tar oxzf /tmp/ansible-result-hk5asxae.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2025-10-19 00:28:44.900678 | controller | Run command: docker rm -f f8db3ada86e1868f27ceb852c37b0ee771592ca55d5c0d92e906e19a6db5456f
2025-10-19 00:28:45.200633 | controller | Run command: docker rm -f 64962dea3a0034eaf82025523ae163424c0279799b817b93adcec2c1e65295d6
2025-10-19 00:28:46.021840 | controller | ERROR: Command "docker exec 64962dea3a0034eaf82025523ae163424c0279799b817b93adcec2c1e65295d6 /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata metadata-_d3e6viw.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2025-10-19 00:28:46.404663 | controller | ERROR
2025-10-19 00:28:46.405286 | controller | {
2025-10-19 00:28:46.405420 | controller |   "delta": "0:05:46.368735",
2025-10-19 00:28:46.405532 | controller |   "end": "2025-10-19 00:28:46.116012",
2025-10-19 00:28:46.405621 | controller |   "msg": "non-zero return code",
2025-10-19 00:28:46.405703 | controller |   "rc": 1,
2025-10-19 00:28:46.405775 | controller |   "start": "2025-10-19 00:22:59.747277"
2025-10-19 00:28:46.405840 | controller | }
failure
2025-10-19 00:28:46.414057 | 
2025-10-19 00:28:46.414166 | PLAY RECAP
2025-10-19 00:28:46.414215 | controller | ok: 20 changed: 5 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 0
2025-10-19 00:28:46.414237 | 
2025-10-19 00:28:46.561300 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-10-19 00:28:46.562376 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-10-19 00:28:47.208081 | 
2025-10-19 00:28:47.208216 | PLAY [all]
2025-10-19 00:28:47.283317 | 
2025-10-19 00:28:47.283476 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-10-19 00:28:50.270432 | controller | changed: non-zero return code
2025-10-19 00:28:50.284698 | 
2025-10-19 00:28:50.284891 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-10-19 00:28:50.313283 | controller | skipping: Conditional result was False
2025-10-19 00:28:50.326390 | 
2025-10-19 00:28:50.326571 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-10-19 00:28:50.361859 | 
2025-10-19 00:28:50.362121 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-10-19 00:28:50.408165 | 
2025-10-19 00:28:50.408520 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-10-19 00:28:50.438363 | controller | skipping: Conditional result was False
2025-10-19 00:28:50.446259 | 
2025-10-19 00:28:50.446396 | LOOP [fetch-subunit-output : Generate subunit file]
2025-10-19 00:28:50.480703 | 
2025-10-19 00:28:50.480888 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-10-19 00:28:50.505037 | controller | skipping: Conditional result was False
2025-10-19 00:28:50.514047 | 
2025-10-19 00:28:50.514199 | TASK [fetch-subunit-output : Remove the temporary file]
2025-10-19 00:28:50.539800 | controller | skipping: Conditional result was False
2025-10-19 00:28:50.553274 | 
2025-10-19 00:28:50.553436 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-10-19 00:28:50.577493 | controller | skipping: Conditional result was False
2025-10-19 00:28:50.611120 | 
2025-10-19 00:28:50.611222 | PLAY RECAP
2025-10-19 00:28:50.611263 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-10-19 00:28:50.611282 | 
2025-10-19 00:28:50.722593 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-10-19 00:28:50.723501 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-10-19 00:28:51.442636 | 
2025-10-19 00:28:51.442764 | PLAY [all:!appliance*]
2025-10-19 00:28:51.466635 | 
2025-10-19 00:28:51.466772 | TASK [unregister the node]
2025-10-19 00:28:51.822159 | controller | skipping: Conditional result was False
2025-10-19 00:28:51.838348 | 
2025-10-19 00:28:51.838574 | TASK [include_role : fetch-output]
2025-10-19 00:28:51.907108 | controller | ok
2025-10-19 00:28:51.937595 | 
2025-10-19 00:28:51.937707 | TASK [fetch-output : Set log path for multiple nodes]
2025-10-19 00:28:51.992361 | controller | skipping: Conditional result was False
2025-10-19 00:28:51.998541 | 
2025-10-19 00:28:51.998609 | TASK [fetch-output : Set log path for single node]
2025-10-19 00:28:52.047996 | controller | ok
2025-10-19 00:28:52.053524 | 
2025-10-19 00:28:52.053651 | LOOP [fetch-output : Ensure local output dirs]
2025-10-19 00:28:52.537478 | controller -> localhost | ok: "/var/lib/zuul/builds/38eb9dee3f0d4f1f94732218991f0a7a/work/logs"
2025-10-19 00:28:52.806006 | controller -> localhost | changed: "/var/lib/zuul/builds/38eb9dee3f0d4f1f94732218991f0a7a/work/artifacts"
2025-10-19 00:28:53.059852 | controller -> localhost | changed: "/var/lib/zuul/builds/38eb9dee3f0d4f1f94732218991f0a7a/work/docs"
2025-10-19 00:28:53.075740 | 
2025-10-19 00:28:53.075898 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-10-19 00:28:55.465067 | controller | changed:
2025-10-19 00:28:55.465329 | controller | .d..t...... ./
2025-10-19 00:28:55.465369 | controller | cd+++++++++ controller/
2025-10-19 00:28:55.465419 | controller | changed: All items complete
2025-10-19 00:28:55.465448 | 
2025-10-19 00:28:57.549107 | controller | changed: .d..t...... ./
2025-10-19 00:28:59.711192 | controller | changed: .d..t...... ./
2025-10-19 00:28:59.750442 | 
2025-10-19 00:28:59.750666 | TASK [include_role : fetch-output-openshift]
2025-10-19 00:28:59.777789 | controller | skipping: Conditional result was False
2025-10-19 00:28:59.794466 | 
2025-10-19 00:28:59.794830 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-10-19 00:28:59.832575 | controller | skipping: Conditional result was False
2025-10-19 00:28:59.844616 | controller | skipping: Conditional result was False
2025-10-19 00:28:59.905275 | 
2025-10-19 00:28:59.905399 | PLAY [localhost]
2025-10-19 00:28:59.930261 | 
2025-10-19 00:28:59.930428 | TASK [Run Zuul manifest role]
2025-10-19 00:28:59.954729 | localhost | ok
2025-10-19 00:28:59.974766 | 
2025-10-19 00:28:59.974911 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-10-19 00:29:00.392657 | localhost | changed
2025-10-19 00:29:00.397512 | 
2025-10-19 00:29:00.397579 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-10-19 00:29:00.436980 | localhost | ok
2025-10-19 00:29:00.446312 | 
2025-10-19 00:29:00.446416 | TASK [Set zuul-log-path fact]
2025-10-19 00:29:00.490599 | localhost | ok
2025-10-19 00:29:00.504035 | 
2025-10-19 00:29:00.504116 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-10-19 00:29:00.533216 | localhost | ok
2025-10-19 00:29:00.544335 | 
2025-10-19 00:29:00.544453 | LOOP [Run upload-logs-swift role]
2025-10-19 00:29:00.590289 | localhost | Output suppressed because no_log was given
2025-10-19 00:29:00.615128 | 
2025-10-19 00:29:00.615229 | TASK [Set zuul-log-path fact]
2025-10-19 00:29:00.639360 | localhost | skipping: Conditional result was False
2025-10-19 00:29:00.645262 | 
2025-10-19 00:29:00.645353 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-10-19 00:29:01.066935 | localhost -> localhost | ok: Runtime: 0:00:00.004710
2025-10-19 00:29:01.079147 | 
2025-10-19 00:29:01.079380 | TASK [upload-logs-swift : Upload logs to swift]