2025-12-18 00:07:24.808743 | Job console starting...
2025-12-18 00:07:24.818719 | Updating repositories
2025-12-18 00:07:25.030443 | Preparing job workspace
2025-12-18 00:07:31.354534 | Running Ansible setup...
2025-12-18 00:07:39.603059 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-12-18 00:07:40.231354 | 
2025-12-18 00:07:40.231487 | PLAY [localhost]
2025-12-18 00:07:40.240873 | 
2025-12-18 00:07:40.240979 | TASK [Gathering Facts]
2025-12-18 00:07:41.370381 | localhost | ok
2025-12-18 00:07:41.384156 | 
2025-12-18 00:07:41.384272 | TASK [Setup log path fact]
2025-12-18 00:07:41.403238 | localhost | ok
2025-12-18 00:07:41.415323 | 
2025-12-18 00:07:41.415414 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-18 00:07:41.443664 | localhost | ok
2025-12-18 00:07:41.451548 | 
2025-12-18 00:07:41.451617 | TASK [emit-job-header : Print job information]
2025-12-18 00:07:41.489875 | # Job Information
2025-12-18 00:07:41.490069 | Ansible Version: 2.15.12
2025-12-18 00:07:41.490104 | Job: ansible-test-sanity-docker-stable-2.9
2025-12-18 00:07:41.490130 | Pipeline: periodic
2025-12-18 00:07:41.490149 | Executor: ze01.softwarefactory-project.io
2025-12-18 00:07:41.490166 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-12-18 00:07:41.490186 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/38d/ansible/38de3f2da0724dbfbdcee3e54f415b8d/
2025-12-18 00:07:41.490204 | Event ID: 95f00f3203bf47de8d788a25b598cc45
2025-12-18 00:07:41.494248 | 
2025-12-18 00:07:41.494311 | LOOP [emit-job-header : Print node information]
2025-12-18 00:07:41.605864 | localhost | ok:
2025-12-18 00:07:41.606202 | localhost | # Node Information
2025-12-18 00:07:41.606233 | localhost | Inventory Hostname: controller
2025-12-18 00:07:41.606253 | localhost | Hostname: np0005564918
2025-12-18 00:07:41.606271 | localhost | Username: zuul
2025-12-18 00:07:41.606297 | localhost | Distro: Fedora 37
2025-12-18 00:07:41.606332 | localhost | Provider: ansible-vexxhost-ams1
2025-12-18 00:07:41.606351 | localhost | Region: ams1
2025-12-18 00:07:41.606368 | localhost | Label: ansible-fedora-37-1vcpu
2025-12-18 00:07:41.606385 | localhost | Product Name: OpenStack Nova
2025-12-18 00:07:41.606403 | localhost | Interface IP: 38.129.16.185
2025-12-18 00:07:41.625321 | 
2025-12-18 00:07:41.625536 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-18 00:07:42.112679 | localhost -> localhost | changed
2025-12-18 00:07:42.119775 | 
2025-12-18 00:07:42.119898 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-18 00:07:43.074499 | localhost -> localhost | changed
2025-12-18 00:07:43.102633 | 
2025-12-18 00:07:43.102741 | PLAY [all:!appliance*]
2025-12-18 00:07:43.118913 | 
2025-12-18 00:07:43.119044 | TASK [include_role : start-zuul-console]
2025-12-18 00:07:43.139414 | controller | ok
2025-12-18 00:07:43.152794 | 
2025-12-18 00:07:43.152914 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-18 00:07:44.189285 | controller | ok
2025-12-18 00:07:44.210850 | 
2025-12-18 00:07:44.210995 | TASK [use-our-mirror : Retrieve the IP address]
2025-12-18 00:07:46.779683 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-12-18 00:07:46.786920 | 
2025-12-18 00:07:46.786991 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-12-18 00:07:47.144098 | controller | skipping: Conditional result was False
2025-12-18 00:07:47.161509 | 
2025-12-18 00:07:47.161685 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-12-18 00:07:47.199673 | controller | skipping: Conditional result was False
2025-12-18 00:07:47.218384 | 
2025-12-18 00:07:47.218704 | TASK [use-our-mirror : Create the podman configuration directory]
2025-12-18 00:07:47.245539 | controller | skipping: Conditional result was False
2025-12-18 00:07:47.257525 | 
2025-12-18 00:07:47.257676 | TASK [use-our-mirror : Copy the podman configuration]
2025-12-18 00:07:47.283301 | controller | skipping: Conditional result was False
2025-12-18 00:07:47.295946 | 
2025-12-18 00:07:47.296159 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-12-18 00:07:47.322948 | controller | skipping: Conditional result was False
2025-12-18 00:07:47.330198 | 
2025-12-18 00:07:47.330288 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-12-18 00:07:47.353754 | controller | skipping: Conditional result was False
2025-12-18 00:07:47.365372 | 
2025-12-18 00:07:47.365449 | TASK [Disable Fedora Modular]
2025-12-18 00:07:48.534126 | controller | changed
2025-12-18 00:07:48.541476 | 
2025-12-18 00:07:48.541560 | TASK [Enable EPEL]
2025-12-18 00:07:48.576355 | controller | skipping: Conditional result was False
2025-12-18 00:07:48.583258 | 
2025-12-18 00:07:48.583348 | TASK [Register the RHEL node]
2025-12-18 00:07:48.995005 | 
2025-12-18 00:07:48.995389 | TASK [Show the subscription-manager status]
2025-12-18 00:07:49.376586 | controller | skipping: Conditional result was False
2025-12-18 00:07:49.384112 | 
2025-12-18 00:07:49.384223 | TASK [Enable EPEL on RHEL]
2025-12-18 00:07:49.750905 | controller | skipping: Conditional result was False
2025-12-18 00:07:49.765211 | 
2025-12-18 00:07:49.765380 | TASK [Install git and tox]
2025-12-18 00:10:12.206172 | controller | changed
2025-12-18 00:10:12.214558 | 
2025-12-18 00:10:12.214945 | TASK [include_role : prepare-workspace]
2025-12-18 00:10:12.251066 | controller | ok
2025-12-18 00:10:12.275701 | 
2025-12-18 00:10:12.276152 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-18 00:10:13.129110 | controller | ok
2025-12-18 00:10:13.146826 | 
2025-12-18 00:10:13.146947 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-18 00:10:34.395626 | controller | Output suppressed because no_log was given
2025-12-18 00:10:34.413362 | 
2025-12-18 00:10:34.413498 | TASK [include_role : prepare-workspace-openshift]
2025-12-18 00:10:34.432356 | controller | skipping: Conditional result was False
2025-12-18 00:10:34.457482 | 
2025-12-18 00:10:34.457566 | PLAY [all:!appliance]
2025-12-18 00:10:34.476465 | 
2025-12-18 00:10:34.476606 | TASK [Run add-build-sshkey role (RSA)]
2025-12-18 00:10:34.498532 | controller | ok
2025-12-18 00:10:34.517568 | 
2025-12-18 00:10:34.517712 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-18 00:10:34.757454 | controller -> localhost | ok
2025-12-18 00:10:34.765506 | 
2025-12-18 00:10:34.765651 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-18 00:10:34.787389 | controller | ok
2025-12-18 00:10:34.803320 | controller | included: /var/lib/zuul/builds/38de3f2da0724dbfbdcee3e54f415b8d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-18 00:10:34.810177 | 
2025-12-18 00:10:34.810275 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-18 00:10:35.255651 | controller -> localhost | Generating public/private rsa key pair.
2025-12-18 00:10:35.255873 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/38de3f2da0724dbfbdcee3e54f415b8d/work/38de3f2da0724dbfbdcee3e54f415b8d_id_rsa.
2025-12-18 00:10:35.255904 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/38de3f2da0724dbfbdcee3e54f415b8d/work/38de3f2da0724dbfbdcee3e54f415b8d_id_rsa.pub.
2025-12-18 00:10:35.255926 | controller -> localhost | The key fingerprint is:
2025-12-18 00:10:35.255947 | controller -> localhost | SHA256:lkKkMXP6PGgsr+H/6JG+lZaMDknc5fTRgfxc69sO+xE zuul-build-sshkey
2025-12-18 00:10:35.255978 | controller -> localhost | The key's randomart image is:
2025-12-18 00:10:35.255998 | controller -> localhost | +---[RSA 2048]----+
2025-12-18 00:10:35.256018 | controller -> localhost | |    + o. ..      |
2025-12-18 00:10:35.256060 | controller -> localhost | |     O  o. ..    |
2025-12-18 00:10:35.256081 | controller -> localhost | |    o + .o.. .   |
2025-12-18 00:10:35.256101 | controller -> localhost | | . o O . oo .    |
2025-12-18 00:10:35.256121 | controller -> localhost | |  + = * S  .  E  |
2025-12-18 00:10:35.256148 | controller -> localhost | | . = + *    .  . |
2025-12-18 00:10:35.256174 | controller -> localhost | |  + = *     .o.  |
2025-12-18 00:10:35.256196 | controller -> localhost | | . * =      .o.. |
2025-12-18 00:10:35.256216 | controller -> localhost | |  ooBo.     .oo  |
2025-12-18 00:10:35.256238 | controller -> localhost | +----[SHA256]-----+
2025-12-18 00:10:35.256309 | controller -> localhost | ok: Runtime: 0:00:00.053684
2025-12-18 00:10:35.263701 | 
2025-12-18 00:10:35.263779 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-18 00:10:35.285349 | controller | ok
2025-12-18 00:10:35.295641 | controller | included: /var/lib/zuul/builds/38de3f2da0724dbfbdcee3e54f415b8d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-18 00:10:35.305583 | 
2025-12-18 00:10:35.305731 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-18 00:10:35.320225 | controller | skipping: Conditional result was False
2025-12-18 00:10:35.328056 | 
2025-12-18 00:10:35.328191 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-18 00:10:36.439940 | controller | changed
2025-12-18 00:10:36.449368 | 
2025-12-18 00:10:36.449485 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-18 00:10:37.123603 | controller | ok
2025-12-18 00:10:37.130617 | 
2025-12-18 00:10:37.130715 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-18 00:10:40.163726 | controller | changed
2025-12-18 00:10:40.171980 | 
2025-12-18 00:10:40.172123 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-18 00:10:43.254469 | controller | changed
2025-12-18 00:10:43.266303 | 
2025-12-18 00:10:43.267080 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-18 00:10:43.295329 | controller | skipping: Conditional result was False
2025-12-18 00:10:43.306762 | 
2025-12-18 00:10:43.306889 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-18 00:10:43.682493 | controller -> localhost | changed
2025-12-18 00:10:43.697459 | 
2025-12-18 00:10:43.697595 | TASK [add-build-sshkey : Add back temp key]
2025-12-18 00:10:43.999444 | controller -> localhost | Identity added: /var/lib/zuul/builds/38de3f2da0724dbfbdcee3e54f415b8d/work/38de3f2da0724dbfbdcee3e54f415b8d_id_rsa (zuul-build-sshkey)
2025-12-18 00:10:43.999654 | controller -> localhost | ok: Runtime: 0:00:00.008810
2025-12-18 00:10:44.006457 | 
2025-12-18 00:10:44.006542 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-18 00:10:44.929105 | controller | ok
2025-12-18 00:10:44.936816 | 
2025-12-18 00:10:44.936948 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-18 00:10:44.963362 | controller | skipping: Conditional result was False
2025-12-18 00:10:44.979741 | 
2025-12-18 00:10:44.979868 | TASK [Run add-build-sshkey role (ECDSA)]
2025-12-18 00:10:45.003120 | controller | ok
2025-12-18 00:10:45.027228 | 
2025-12-18 00:10:45.027365 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-18 00:10:45.258654 | controller -> localhost | ok
2025-12-18 00:10:45.269490 | 
2025-12-18 00:10:45.269629 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-18 00:10:45.300616 | controller | ok
2025-12-18 00:10:45.311511 | controller | included: /var/lib/zuul/builds/38de3f2da0724dbfbdcee3e54f415b8d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-18 00:10:45.317808 | 
2025-12-18 00:10:45.317910 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-18 00:10:45.597785 | controller -> localhost | Generating public/private ecdsa key pair.
2025-12-18 00:10:45.597998 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/38de3f2da0724dbfbdcee3e54f415b8d/work/38de3f2da0724dbfbdcee3e54f415b8d_id_ecdsa.
2025-12-18 00:10:45.598055 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/38de3f2da0724dbfbdcee3e54f415b8d/work/38de3f2da0724dbfbdcee3e54f415b8d_id_ecdsa.pub.
2025-12-18 00:10:45.598093 | controller -> localhost | The key fingerprint is:
2025-12-18 00:10:45.598113 | controller -> localhost | SHA256:/Ix9yOz7FKk5R/mxwHtf2bNaEu3/t7JDoP5TyAvXnAw zuul-build-sshkey
2025-12-18 00:10:45.598131 | controller -> localhost | The key's randomart image is:
2025-12-18 00:10:45.598149 | controller -> localhost | +---[ECDSA 521]---+
2025-12-18 00:10:45.598167 | controller -> localhost | |                 |
2025-12-18 00:10:45.598184 | controller -> localhost | |                 |
2025-12-18 00:10:45.598200 | controller -> localhost | |                 |
2025-12-18 00:10:45.598217 | controller -> localhost | |       .   E o.  |
2025-12-18 00:10:45.598233 | controller -> localhost | |        S o #.o. |
2025-12-18 00:10:45.598250 | controller -> localhost | |         X O %ooo|
2025-12-18 00:10:45.598266 | controller -> localhost | |        o & O.+=o|
2025-12-18 00:10:45.598284 | controller -> localhost | |         o B +o.*|
2025-12-18 00:10:45.598301 | controller -> localhost | |          ++oo=oB|
2025-12-18 00:10:45.598317 | controller -> localhost | +----[SHA256]-----+
2025-12-18 00:10:45.598365 | controller -> localhost | ok: Runtime: 0:00:00.008143
2025-12-18 00:10:45.605222 | 
2025-12-18 00:10:45.605320 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-18 00:10:45.638700 | controller | ok
2025-12-18 00:10:45.649683 | controller | included: /var/lib/zuul/builds/38de3f2da0724dbfbdcee3e54f415b8d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-18 00:10:45.662777 | 
2025-12-18 00:10:45.662925 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-18 00:10:45.689128 | controller | skipping: Conditional result was False
2025-12-18 00:10:45.698677 | 
2025-12-18 00:10:45.698824 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-18 00:10:46.641757 | controller | changed
2025-12-18 00:10:46.649492 | 
2025-12-18 00:10:46.649727 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-18 00:10:47.360410 | controller | ok
2025-12-18 00:10:47.368139 | 
2025-12-18 00:10:47.368271 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-18 00:10:50.423655 | controller | changed
2025-12-18 00:10:50.432392 | 
2025-12-18 00:10:50.432519 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-18 00:10:53.472963 | controller | changed
2025-12-18 00:10:53.480680 | 
2025-12-18 00:10:53.480875 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-18 00:10:53.526349 | controller | skipping: Conditional result was False
2025-12-18 00:10:53.541438 | 
2025-12-18 00:10:53.541609 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-18 00:10:53.824286 | controller -> localhost | changed
2025-12-18 00:10:53.837240 | 
2025-12-18 00:10:53.837335 | TASK [add-build-sshkey : Add back temp key]
2025-12-18 00:10:54.139773 | controller -> localhost | Identity added: /var/lib/zuul/builds/38de3f2da0724dbfbdcee3e54f415b8d/work/38de3f2da0724dbfbdcee3e54f415b8d_id_ecdsa (zuul-build-sshkey)
2025-12-18 00:10:54.140009 | controller -> localhost | ok: Runtime: 0:00:00.013335
2025-12-18 00:10:54.149332 | 
2025-12-18 00:10:54.149411 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-18 00:10:54.847970 | controller | ok
2025-12-18 00:10:54.858803 | 
2025-12-18 00:10:54.858968 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-18 00:10:54.886848 | controller | skipping: Conditional result was False
2025-12-18 00:10:54.901419 | 
2025-12-18 00:10:54.901562 | TASK [include_role : remove-zuul-sshkey]
2025-12-18 00:10:54.917452 | controller | skipping: Conditional result was False
2025-12-18 00:10:54.925463 | 
2025-12-18 00:10:54.925597 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-18 00:10:55.610174 | controller | ok: "logs"
2025-12-18 00:10:55.610808 | controller | ok: All items complete
2025-12-18 00:10:55.610849 | 
2025-12-18 00:10:56.280073 | controller | ok: "artifacts"
2025-12-18 00:10:56.929343 | controller | ok: "docs"
2025-12-18 00:10:56.947154 | 
2025-12-18 00:10:56.947290 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-18 00:10:57.657932 | controller | changed: "logs"
2025-12-18 00:10:58.320522 | controller | changed: "artifacts"
2025-12-18 00:10:58.956473 | controller | changed: "docs"
2025-12-18 00:10:58.987440 | 
2025-12-18 00:10:58.987546 | PLAY RECAP
2025-12-18 00:10:58.987588 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-12-18 00:10:58.987726 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-18 00:10:58.987748 | 
2025-12-18 00:10:59.117718 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-12-18 00:10:59.118762 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-18 00:10:59.930144 | 
2025-12-18 00:10:59.930270 | PLAY [all]
2025-12-18 00:10:59.962518 | 
2025-12-18 00:10:59.962661 | TASK [Install binary dependencies]
2025-12-18 00:11:00.033865 | controller | ok
2025-12-18 00:11:00.059196 | 
2025-12-18 00:11:00.059338 | TASK [bindep : Include find tasks]
2025-12-18 00:11:00.089535 | controller | ok
2025-12-18 00:11:00.101847 | controller | included: /var/lib/zuul/builds/38de3f2da0724dbfbdcee3e54f415b8d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-12-18 00:11:00.114070 | 
2025-12-18 00:11:00.114174 | TASK [bindep : Look for bindep.txt]
2025-12-18 00:11:01.074407 | controller | ok
2025-12-18 00:11:01.081969 | 
2025-12-18 00:11:01.082074 | TASK [bindep : Define bindep_file fact]
2025-12-18 00:11:01.107529 | controller | skipping: Conditional result was False
2025-12-18 00:11:01.117008 | 
2025-12-18 00:11:01.117195 | TASK [bindep : Look for other-requirements.txt]
2025-12-18 00:11:01.781289 | controller | ok
2025-12-18 00:11:01.793636 | 
2025-12-18 00:11:01.793755 | TASK [bindep : Define bindep_file fact]
2025-12-18 00:11:01.824586 | controller | skipping: Conditional result was False
2025-12-18 00:11:01.831184 | 
2025-12-18 00:11:01.831287 | TASK [bindep : Look for bindep fallback file]
2025-12-18 00:11:01.855928 | controller | skipping: Conditional result was False
2025-12-18 00:11:01.861924 | 
2025-12-18 00:11:01.862005 | TASK [bindep : Define bindep_file fact]
2025-12-18 00:11:01.894065 | controller | skipping: Conditional result was False
2025-12-18 00:11:01.900720 | 
2025-12-18 00:11:01.900804 | TASK [bindep : Include bindep tasks]
2025-12-18 00:11:01.975391 | controller | skipping: Conditional result was False
2025-12-18 00:11:01.982121 | 
2025-12-18 00:11:01.982207 | TASK [bindep : Include install tasks]
2025-12-18 00:11:02.017603 | controller | skipping: Conditional result was False
2025-12-18 00:11:02.024362 | 
2025-12-18 00:11:02.024462 | LOOP [bindep : Include package tasks]
2025-12-18 00:11:02.121294 | 
2025-12-18 00:11:02.121459 | TASK [Run test-setup role]
2025-12-18 00:11:02.163851 | controller | ok
2025-12-18 00:11:02.199451 | 
2025-12-18 00:11:02.199571 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-12-18 00:11:02.985857 | controller | ok
2025-12-18 00:11:02.991893 | 
2025-12-18 00:11:02.991968 | TASK [test-setup : Run tools/test-setup.sh]
2025-12-18 00:11:03.366256 | controller | skipping: Conditional result was False
2025-12-18 00:11:03.414655 | 
2025-12-18 00:11:03.414777 | PLAY RECAP
2025-12-18 00:11:03.414825 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-18 00:11:03.414846 | 
2025-12-18 00:11:03.529852 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-18 00:11:03.530978 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-12-18 00:11:04.357870 | 
2025-12-18 00:11:04.358101 | PLAY [controller]
2025-12-18 00:11:04.381680 | 
2025-12-18 00:11:04.381853 | TASK [Create the /root directory]
2025-12-18 00:11:05.516893 | controller | ok
2025-12-18 00:11:05.523177 | 
2025-12-18 00:11:05.523293 | TASK [Install glibc-langpack-en]
2025-12-18 00:11:14.383005 | controller | ok: Nothing to do
2025-12-18 00:11:14.389849 | 
2025-12-18 00:11:14.389965 | TASK [Ensure controller directory exists]
2025-12-18 00:11:15.187198 | controller | changed
2025-12-18 00:11:15.194399 | 
2025-12-18 00:11:15.194503 | TASK [Install container runtime]
2025-12-18 00:11:15.259390 | controller | ok
2025-12-18 00:11:15.329327 | 
2025-12-18 00:11:15.329465 | LOOP [ensure-docker : Gather variables for each operating system]
2025-12-18 00:11:15.369649 | controller | ok: "/var/lib/zuul/builds/38de3f2da0724dbfbdcee3e54f415b8d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-12-18 00:11:15.384929 | 
2025-12-18 00:11:15.385090 | TASK [ensure-docker : Sanity check for distro selection]
2025-12-18 00:11:15.411032 | controller | skipping: Conditional result was False
2025-12-18 00:11:15.421376 | 
2025-12-18 00:11:15.421541 | TASK [ensure-docker : Sanity check for distro install]
2025-12-18 00:11:15.447846 | controller | skipping: Conditional result was False
2025-12-18 00:11:15.456411 | 
2025-12-18 00:11:15.456527 | TASK [ensure-docker : Sanity check for upstream install]
2025-12-18 00:11:15.480919 | controller | skipping: Conditional result was False
2025-12-18 00:11:15.491636 | 
2025-12-18 00:11:15.491771 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-12-18 00:11:15.518215 | controller | skipping: Conditional result was False
2025-12-18 00:11:15.525894 | 
2025-12-18 00:11:15.525989 | TASK [ensure-docker : Include tasks for docker install]
2025-12-18 00:11:15.546888 | controller | ok
2025-12-18 00:11:15.558586 | controller | included: /var/lib/zuul/builds/38de3f2da0724dbfbdcee3e54f415b8d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-12-18 00:11:15.567984 | 
2025-12-18 00:11:15.568097 | TASK [ensure-docker : Not Implemented]
2025-12-18 00:11:15.590957 | controller | skipping: Conditional result was False
2025-12-18 00:11:15.599553 | 
2025-12-18 00:11:15.599646 | TASK [Set up docker mirrors]
2025-12-18 00:11:15.636349 | controller | ok
2025-12-18 00:11:15.658162 | 
2025-12-18 00:11:15.658277 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-12-18 00:11:15.703360 | controller | skipping: Conditional result was False
2025-12-18 00:11:15.739690 | 
2025-12-18 00:11:15.739820 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-12-18 00:11:15.764820 | controller | skipping: Conditional result was False
2025-12-18 00:11:15.773984 | 
2025-12-18 00:11:15.774154 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-12-18 00:11:15.798855 | controller | skipping: Conditional result was False
2025-12-18 00:11:15.807546 | 
2025-12-18 00:11:15.807660 | TASK [use-docker-mirror : Set up docker mirrors]
2025-12-18 00:11:15.831420 | controller | skipping: Conditional result was False
2025-12-18 00:11:15.846216 | 
2025-12-18 00:11:15.846355 | TASK [Add all repositories]
2025-12-18 00:11:15.871216 | controller | ok
2025-12-18 00:11:15.913178 | 
2025-12-18 00:11:15.913338 | LOOP [ensure-package-repositories : Install repositories]
2025-12-18 00:11:15.941231 | controller | ok: "/var/lib/zuul/builds/38de3f2da0724dbfbdcee3e54f415b8d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-12-18 00:11:15.941492 | controller | ok: All items complete
2025-12-18 00:11:15.941527 | 
2025-12-18 00:11:15.961116 | controller | included: /var/lib/zuul/builds/38de3f2da0724dbfbdcee3e54f415b8d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-12-18 00:11:15.968183 | 
2025-12-18 00:11:15.968309 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-12-18 00:11:16.934510 | controller | changed
2025-12-18 00:11:16.942622 | 
2025-12-18 00:11:16.942753 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-12-18 00:11:20.173975 | controller | changed:
2025-12-18 00:11:20.174188 | controller | {
2025-12-18 00:11:20.174228 | 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-12-18 00:11:20.174258 | controller | }
2025-12-18 00:11:20.183087 | 
2025-12-18 00:11:20.183224 | LOOP [ensure-package-repositories : Import GPG keys]
2025-12-18 00:11:21.510269 | controller | changed:
2025-12-18 00:11:21.510446 | controller | {
2025-12-18 00:11:21.510477 | 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-12-18 00:11:21.510500 | controller | }
2025-12-18 00:11:21.510530 | controller | changed: All items complete
2025-12-18 00:11:21.510556 | 
2025-12-18 00:11:21.518623 | 
2025-12-18 00:11:21.518764 | TASK [ensure-package-repositories : Remove GPG temp directory]
2025-12-18 00:11:22.231246 | controller | changed
2025-12-18 00:11:22.237545 | 
2025-12-18 00:11:22.237624 | LOOP [ensure-package-repositories : Add repositories]
2025-12-18 00:11:23.130093 | controller | changed:
2025-12-18 00:11:23.130285 | controller | {
2025-12-18 00:11:23.130323 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2025-12-18 00:11:23.130355 | controller |   "description": "Docker CE Stable - $basearch",
2025-12-18 00:11:23.130382 | controller |   "gpgcheck": true,
2025-12-18 00:11:23.130411 | controller |   "name": "docker-ce-stable"
2025-12-18 00:11:23.130441 | controller | }
2025-12-18 00:11:23.150276 | 
2025-12-18 00:11:23.150407 | TASK [ensure-docker : Install pre-reqs]
2025-12-18 00:11:28.765083 | controller | ok: Nothing to do
2025-12-18 00:11:28.773717 | 
2025-12-18 00:11:28.773817 | TASK [ensure-docker : Remove packages]
2025-12-18 00:11:32.195928 | controller | ok: Nothing to do
2025-12-18 00:11:32.206858 | 
2025-12-18 00:11:32.207076 | TASK [ensure-docker : Install upstream docker using package]
2025-12-18 00:13:04.953170 | controller | changed
2025-12-18 00:13:05.000896 | 
2025-12-18 00:13:05.001265 | TASK [ensure-docker : Setup docker]
2025-12-18 00:13:05.027233 | controller | ok
2025-12-18 00:13:05.044152 | controller | included: /var/lib/zuul/builds/38de3f2da0724dbfbdcee3e54f415b8d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2025-12-18 00:13:05.059760 | 
2025-12-18 00:13:05.059879 | TASK [ensure-docker : Ensure "docker" group exists]
2025-12-18 00:13:06.155096 | controller | ok
2025-12-18 00:13:06.161750 | 
2025-12-18 00:13:06.161875 | TASK [ensure-docker : Add user to docker group]
2025-12-18 00:13:07.405743 | controller | changed
2025-12-18 00:13:07.418126 | 
2025-12-18 00:13:07.418327 | TASK [Update docker daemon proxy configuration]
2025-12-18 00:13:07.444547 | controller | skipping: Conditional result was False
2025-12-18 00:13:07.455840 | 
2025-12-18 00:13:07.455970 | TASK [Lower default MTU]
2025-12-18 00:13:07.472347 | controller | skipping: Conditional result was False
2025-12-18 00:13:07.483785 | 
2025-12-18 00:13:07.483929 | TASK [ensure-docker : Get the status of the docket.socket unit]
2025-12-18 00:13:08.312425 | controller | inactive
2025-12-18 00:13:08.855616 | controller | ok: Runtime: 0:00:00.016837
2025-12-18 00:13:08.862477 | 
2025-12-18 00:13:08.862584 | TASK [ensure-docker : Get the status of the docket unit]
2025-12-18 00:13:09.634007 | controller | inactive
2025-12-18 00:13:10.232041 | controller | ok: Runtime: 0:00:00.031631
2025-12-18 00:13:10.239003 | 
2025-12-18 00:13:10.239135 | TASK [ensure-docker : Restart docker]
2025-12-18 00:13:10.272493 | Trigger a Docker restart
2025-12-18 00:13:10.281694 | 
2025-12-18 00:13:10.281840 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2025-12-18 00:13:10.313687 | 
2025-12-18 00:13:10.313812 | TASK [ensure-docker : Install docker-compose]
2025-12-18 00:13:10.328304 | controller | skipping: Conditional result was False
2025-12-18 00:13:10.336736 | 
2025-12-18 00:13:10.336868 | TASK [ensure-docker : Flush handlers before role exit]
2025-12-18 00:13:10.351224 | 
2025-12-18 00:13:10.351353 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2025-12-18 00:13:13.897894 | controller | changed
2025-12-18 00:13:13.920875 | 
2025-12-18 00:13:13.921005 | TASK [ensure-docker : Assure docker service is running]
2025-12-18 00:13:18.169287 | controller | changed
2025-12-18 00:13:18.180325 | 
2025-12-18 00:13:18.180454 | TASK [ensure-docker : Assure docker.socket service is running]
2025-12-18 00:13:19.591886 | controller | ok
2025-12-18 00:13:19.600787 | 
2025-12-18 00:13:19.600897 | TASK [ensure-docker : Correct group ownership on docker sock]
2025-12-18 00:13:20.362354 | controller | ok
2025-12-18 00:13:20.372000 | 
2025-12-18 00:13:20.372172 | TASK [ensure-docker : Validate ability to talk with docker]
2025-12-18 00:13:21.006677 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2025-12-18 00:13:21.236221 | controller | ok: Runtime: 0:00:00.036332
2025-12-18 00:13:21.247071 | 
2025-12-18 00:13:21.247245 | TASK [ensure-docker : Get version details]
2025-12-18 00:13:21.908093 | controller | Client: Docker Engine - Community
2025-12-18 00:13:21.908168 | controller |  Version:           24.0.7
2025-12-18 00:13:21.908275 | controller |  API version:       1.43
2025-12-18 00:13:21.908288 | controller |  Go version:        go1.20.10
2025-12-18 00:13:21.908306 | controller |  Git commit:        afdd53b
2025-12-18 00:13:21.908314 | controller |  Built:             Thu Oct 26 09:09:39 2023
2025-12-18 00:13:21.908321 | controller |  OS/Arch:           linux/amd64
2025-12-18 00:13:21.908330 | controller |  Context:           default
2025-12-18 00:13:21.908351 | controller |
2025-12-18 00:13:21.908357 | controller | Server: Docker Engine - Community
2025-12-18 00:13:21.908364 | controller |  Engine:
2025-12-18 00:13:21.908373 | controller |   Version:          24.0.7
2025-12-18 00:13:21.908380 | controller |   API version:      1.43 (minimum version 1.12)
2025-12-18 00:13:21.908386 | controller |   Go version:       go1.20.10
2025-12-18 00:13:21.908399 | controller |   Git commit:       311b9ff
2025-12-18 00:13:21.908406 | controller |   Built:            Thu Oct 26 09:08:11 2023
2025-12-18 00:13:21.908412 | controller |   OS/Arch:          linux/amd64
2025-12-18 00:13:21.908418 | controller |   Experimental:     false
2025-12-18 00:13:21.908424 | controller |  containerd:
2025-12-18 00:13:21.908429 | controller |   Version:          1.6.25
2025-12-18 00:13:21.908456 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2025-12-18 00:13:21.908463 | controller |  runc:
2025-12-18 00:13:21.908469 | controller |   Version:          1.1.10
2025-12-18 00:13:21.908475 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2025-12-18 00:13:21.908484 | controller |  docker-init:
2025-12-18 00:13:21.908493 | controller |   Version:          0.19.0
2025-12-18 00:13:21.911354 | controller |   GitCommit:        de40ad0
2025-12-18 00:13:22.114438 | controller | ok: Runtime: 0:00:00.045933
2025-12-18 00:13:22.126495 | 
2025-12-18 00:13:22.126719 | TASK [ensure-docker : Dump installed docker details]
2025-12-18 00:13:22.198245 | {'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-12-18 00:13:21.866848', 'end': '2025-12-18 00:13:21.912781', 'delta': '0:00:00.045933', 'msg': '', 'zuul_log_id': 'fa163ec2-ffbe-ee2a-0484-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-12-18 00:13:22.208678 | 
2025-12-18 00:13:22.208831 | TASK [Use buildset registry]
2025-12-18 00:13:22.234115 | controller | skipping: Conditional result was False
2025-12-18 00:13:22.247933 | 
2025-12-18 00:13:22.248044 | TASK [Ensure python3.8 is present]
2025-12-18 00:13:22.272365 | controller | skipping: Conditional result was False
2025-12-18 00:13:22.279553 | 
2025-12-18 00:13:22.279640 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-12-18 00:13:22.299291 | controller | ok
2025-12-18 00:13:22.323310 | 
2025-12-18 00:13:22.323440 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-12-18 00:13:28.794254 | controller | ok: Nothing to do
2025-12-18 00:13:28.803308 | 
2025-12-18 00:13:28.803408 | TASK [our-ensure-python : Also install python3-devel]
2025-12-18 00:13:48.544640 | controller | changed
2025-12-18 00:13:48.558744 | 
2025-12-18 00:13:48.558872 | TASK [Run ensure-virtualenv role]
2025-12-18 00:13:48.580503 | controller | ok
2025-12-18 00:13:48.611572 | 
2025-12-18 00:13:48.611701 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-12-18 00:13:49.406077 | controller | /usr/bin/virtualenv
2025-12-18 00:13:49.966653 | controller | ok: Runtime: 0:00:00.011989
2025-12-18 00:13:49.974099 | 
2025-12-18 00:13:49.974220 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-12-18 00:13:49.994690 | controller | skipping: Conditional result was False
2025-12-18 00:13:49.994945 | controller | changed: All items complete
2025-12-18 00:13:49.994978 | 
2025-12-18 00:13:50.012088 | 
2025-12-18 00:13:50.012238 | TASK [Find the full path of the Python interpreter]
2025-12-18 00:13:50.758886 | controller | /usr/bin/python3.8
2025-12-18 00:13:51.377977 | controller | ok
2025-12-18 00:13:51.386410 | 
2025-12-18 00:13:51.386525 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-12-18 00:13:53.915374 | controller | created virtual environment CPython3.8.18.final.0-64 in 1352ms
2025-12-18 00:13:54.071647 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-12-18 00:13:54.071702 | 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-12-18 00:13:54.071718 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-12-18 00:13:54.071736 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-12-18 00:13:54.270173 | controller | changed
2025-12-18 00:13:54.278824 | 
2025-12-18 00:13:54.278957 | TASK [Set selinux package]
2025-12-18 00:13:54.301329 | controller | ok
2025-12-18 00:13:54.309105 | 
2025-12-18 00:13:54.309225 | TASK [Set selinux package (Fedora)]
2025-12-18 00:13:54.342622 | controller | ok
2025-12-18 00:13:54.352161 | 
2025-12-18 00:13:54.352425 | TASK [Install selinux into virtualenv]
2025-12-18 00:14:01.232258 | controller | Collecting selinux-please-lie-to-me
2025-12-18 00:14:01.382960 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-12-18 00:14:02.103440 | controller | Collecting setuptools<50.0.0
2025-12-18 00:14:02.110388 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-12-18 00:14:02.211424 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 8.3 MB/s eta 0:00:00
2025-12-18 00:14:02.370780 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-12-18 00:14:02.371719 | controller |   Attempting uninstall: setuptools
2025-12-18 00:14:02.371749 | controller |     Found existing installation: setuptools 62.6.0
2025-12-18 00:14:02.529618 | controller |     Uninstalling setuptools-62.6.0:
2025-12-18 00:14:02.552591 | controller |       Successfully uninstalled setuptools-62.6.0
2025-12-18 00:14:03.297829 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-12-18 00:14:03.534382 | controller |
2025-12-18 00:14:03.830457 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-12-18 00:14:03.830517 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-12-18 00:14:04.229084 | controller | ok: Runtime: 0:00:08.207973
2025-12-18 00:14:04.237655 | 
2025-12-18 00:14:04.237809 | TASK [Install pytest-forked into virtualenv]
2025-12-18 00:14:05.959573 | controller | Collecting pytest-forked
2025-12-18 00:14:06.035833 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-12-18 00:14:06.135108 | controller | Collecting py
2025-12-18 00:14:06.141512 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-12-18 00:14:06.184174 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 2.4 MB/s eta 0:00:00
2025-12-18 00:14:06.453041 | controller | Collecting pytest>=3.10
2025-12-18 00:14:06.460659 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2025-12-18 00:14:06.485124 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 16.3 MB/s eta 0:00:00
2025-12-18 00:14:06.608392 | controller | Collecting pluggy<2,>=1.5
2025-12-18 00:14:06.615800 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-12-18 00:14:06.709766 | controller | Collecting packaging
2025-12-18 00:14:06.716506 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-12-18 00:14:06.737720 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 3.8 MB/s eta 0:00:00
2025-12-18 00:14:06.802259 | controller | Collecting exceptiongroup>=1.0.0rc8
2025-12-18 00:14:06.808161 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2025-12-18 00:14:06.911591 | controller | Collecting tomli>=1
2025-12-18 00:14:06.920139 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2025-12-18 00:14:06.980668 | controller | Collecting iniconfig
2025-12-18 00:14:06.987446 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-12-18 00:14:07.104807 | controller | Collecting typing-extensions>=4.6.0
2025-12-18 00:14:07.113305 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2025-12-18 00:14:07.126989 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 4.4 MB/s eta 0:00:00
2025-12-18 00:14:07.275045 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-12-18 00:14:08.521642 | controller | Successfully installed exceptiongroup-1.3.1 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-12-18 00:14:08.528991 | controller |
2025-12-18 00:14:08.767710 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-12-18 00:14:08.767763 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-12-18 00:14:09.112840 | controller | ok: Runtime: 0:00:03.731528
2025-12-18 00:14:09.121464 | 
2025-12-18 00:14:09.121624 | TASK [Update pip]
2025-12-18 00:14:11.529270 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2025-12-18 00:14:11.879389 | controller | Collecting pip
2025-12-18 00:14:11.946471 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2025-12-18 00:14:12.036590 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 24.2 MB/s eta 0:00:00
2025-12-18 00:14:12.174942 | controller | Installing collected packages: pip
2025-12-18 00:14:12.176198 | controller |   Attempting uninstall: pip
2025-12-18 00:14:12.176499 | controller |     Found existing installation: pip 22.2.2
2025-12-18 00:14:12.801980 | controller |     Uninstalling pip-22.2.2:
2025-12-18 00:14:12.892650 | controller |       Successfully uninstalled pip-22.2.2
2025-12-18 00:14:15.398548 | controller | Successfully installed pip-25.0.1
2025-12-18 00:14:16.014633 | controller | ok: Runtime: 0:00:05.466436
2025-12-18 00:14:16.022053 | 
2025-12-18 00:14:16.022141 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-12-18 00:14:17.091089 | controller | changed
2025-12-18 00:14:17.138607 | 
2025-12-18 00:14:17.138748 | TASK [Install ansible into virtualenv]
2025-12-18 00:14:19.177742 | controller | Processing ./src/github.com/ansible/ansible
2025-12-18 00:14:19.184380 | controller |   Preparing metadata (setup.py): started
2025-12-18 00:14:21.232310 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-12-18 00:14:21.322858 | controller | Collecting jinja2 (from ansible==2.9.27.post0)
2025-12-18 00:14:21.341908 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-12-18 00:14:21.449878 | controller | Collecting PyYAML (from ansible==2.9.27.post0)
2025-12-18 00:14:21.457266 | 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-12-18 00:14:22.015090 | controller | Collecting cryptography (from ansible==2.9.27.post0)
2025-12-18 00:14:22.021256 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-12-18 00:14:22.319833 | controller | Collecting cffi>=1.14 (from cryptography->ansible==2.9.27.post0)
2025-12-18 00:14:22.325562 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2025-12-18 00:14:22.340848 | 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-12-18 00:14:22.479359 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible==2.9.27.post0)
2025-12-18 00:14:22.487588 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2025-12-18 00:14:22.534604 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible==2.9.27.post0)
2025-12-18 00:14:22.541349 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-12-18 00:14:22.574652 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2025-12-18 00:14:22.634111 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 88.0 MB/s eta 0:00:00
2025-12-18 00:14:22.643116 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-12-18 00:14:22.666935 | 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-12-18 00:14:22.696331 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 27.1 MB/s eta 0:00:00
2025-12-18 00:14:22.706917 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2025-12-18 00:14:22.737878 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2025-12-18 00:14:22.761094 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-12-18 00:14:22.801248 | controller | Building wheels for collected packages: ansible
2025-12-18 00:14:22.804769 | controller |   Building wheel for ansible (setup.py): started
2025-12-18 00:14:34.285659 | controller |   Building wheel for ansible (setup.py): finished with status 'done'
2025-12-18 00:14:34.305713 | controller |   Created wheel for ansible: filename=ansible-2.9.27.post0-py3-none-any.whl size=16207514 sha256=05433d5f41afa6a33bda98c9ac6c3718d27213ad21085bc5c974707a9e638a7c
2025-12-18 00:14:34.305937 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-brqhbst9/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2025-12-18 00:14:34.391704 | controller | Successfully built ansible
2025-12-18 00:14:34.599506 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible
2025-12-18 00:14:51.150664 | 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-12-18 00:14:51.533407 | controller | ok: Runtime: 0:00:33.301983
2025-12-18 00:14:51.542844 | 
2025-12-18 00:14:51.542968 | TASK [Setup download-artifact-fork role]
2025-12-18 00:14:51.568941 | controller | ok
2025-12-18 00:14:51.593970 | 
2025-12-18 00:14:51.594120 | TASK [download-artifact-fork : Parse inventory response]
2025-12-18 00:14:51.626806 | controller | ok
2025-12-18 00:14:51.636659 | 
2025-12-18 00:14:51.636779 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-12-18 00:14:51.661716 | controller | skipping: Conditional result was False
2025-12-18 00:14:51.669899 | 
2025-12-18 00:14:51.670031 | TASK [download-artifact-fork : Parse build response]
2025-12-18 00:14:51.694645 | controller | skipping: Conditional result was False
2025-12-18 00:14:51.703083 | 
2025-12-18 00:14:51.703207 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-12-18 00:14:52.577172 | controller | changed
2025-12-18 00:14:52.589395 | 
2025-12-18 00:14:52.589555 | LOOP [download-artifact-fork : Download archive by type]
2025-12-18 00:14:52.639536 | controller | skipping: Conditional result was False
2025-12-18 00:14:52.639812 | 
2025-12-18 00:14:55.209528 | controller | changed: OK (20199 bytes)
2025-12-18 00:14:55.241558 | 
2025-12-18 00:14:55.241694 | TASK [deploy-artifacts : define __collections]
2025-12-18 00:14:55.266665 | controller | ok
2025-12-18 00:14:55.277196 | 
2025-12-18 00:14:55.277314 | LOOP [deploy-artifacts : Create list of collections]
2025-12-18 00:14:55.303341 | controller | skipping: Conditional result was False
2025-12-18 00:14:55.312876 | controller | ok:
2025-12-18 00:14:55.312996 | controller | {
2025-12-18 00:14:55.313056 | controller |   "branch": "main",
2025-12-18 00:14:55.313078 | controller |   "job": "build-ansible-collection",
2025-12-18 00:14:55.313095 | controller |   "metadata": {
2025-12-18 00:14:55.313112 | controller |     "type": "ansible_collection",
2025-12-18 00:14:55.313128 | controller |     "version": "5.0.0"
2025-12-18 00:14:55.313143 | controller |   },
2025-12-18 00:14:55.313159 | controller |   "name": "ansible.network",
2025-12-18 00:14:55.313174 | controller |   "project": "ansible-collections/ansible.network",
2025-12-18 00:14:55.313190 | controller |   "url": "https://7bfdff24fd69c05d87b3-1058dc3e51dc205c011a13c69f0de41f.ssl.cf2.rackcdn.com/ansible/6dd4976c9a9f4c06b5de144213bc0a17/artifacts/ansible-network-5.0.0.tar.gz"
2025-12-18 00:14:55.313213 | controller | }
2025-12-18 00:14:55.322932 | 
2025-12-18 00:14:55.323091 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-12-18 00:14:56.623589 | controller | Process install dependency map
2025-12-18 00:15:52.997889 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\Starting collection install process
2025-12-18 00:15:53.098776 | controller | |Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2025-12-18 00:15:53.098843 | controller | Installing 'ansible.netcommon:8.2.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2025-12-18 00:15:54.809274 | controller | /-\|/-\|/-\|/-\|/Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2025-12-18 00:15:56.419583 | controller | -\|/-\|/-\|/-\|/Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2025-12-18 00:15:58.754647 | controller | -\|/-\|/-\|/-\|/-\|/-\|Installing 'cisco.ios:11.2.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2025-12-18 00:16:02.207661 | controller | /-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2025-12-18 00:16:04.927188 | controller | \|/-\|/-\|/-\|/-\|/-\|/-\|/Installing 'cisco.nxos:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2025-12-18 00:16:09.194286 | controller | -\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2025-12-18 00:16:12.238906 | controller | ok: Runtime: 0:01:15.658956
2025-12-18 00:16:12.260668 | 
2025-12-18 00:16:12.260814 | TASK [podman may need iptables kernel modules]
2025-12-18 00:16:13.638079 | controller | ok: Runtime: 0:00:00.008253
2025-12-18 00:16:13.667878 | 
2025-12-18 00:16:13.667986 | PLAY RECAP
2025-12-18 00:16:13.668062 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2025-12-18 00:16:13.668099 | 
2025-12-18 00:16:13.855823 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-12-18 00:16:13.857198 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-12-18 00:16:14.788611 | 
2025-12-18 00:16:14.788740 | PLAY [controller]
2025-12-18 00:16:14.811977 | 
2025-12-18 00:16:14.812129 | TASK [Copy the galaxy.yml on the controller]
2025-12-18 00:16:18.493799 | controller | changed
2025-12-18 00:16:18.502793 | 
2025-12-18 00:16:18.502910 | TASK [Load information from galaxy.yml]
2025-12-18 00:16:18.536516 | controller | ok
2025-12-18 00:16:18.545816 | 
2025-12-18 00:16:18.545942 | LOOP [Enable FIPS mode]
2025-12-18 00:16:18.567974 | 
2025-12-18 00:16:18.568142 | LOOP [ansible-test : Include branch specific variables.]
2025-12-18 00:16:18.610058 | controller | ok: "/var/lib/zuul/builds/38de3f2da0724dbfbdcee3e54f415b8d/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2025-12-18 00:16:18.610222 | 
2025-12-18 00:16:18.620712 | 
2025-12-18 00:16:18.620823 | TASK [ansible-test : Setup --skip-tags for test_options]
2025-12-18 00:16:18.662576 | controller | ok
2025-12-18 00:16:18.668261 | 
2025-12-18 00:16:18.668354 | TASK [ansible-test : Enable --inventory for network-integration]
2025-12-18 00:16:18.685069 | controller | skipping: Conditional result was False
2025-12-18 00:16:18.702569 | 
2025-12-18 00:16:18.702915 | TASK [ansible-test : Enable --retry-on-error]
2025-12-18 00:16:18.719293 | controller | skipping: Conditional result was False
2025-12-18 00:16:18.729659 | 
2025-12-18 00:16:18.729850 | TASK [ansible-test : Enable --continue-on-error]
2025-12-18 00:16:18.762876 | controller | ok
2025-12-18 00:16:18.770964 | 
2025-12-18 00:16:18.771060 | TASK [ansible-test : Enable --requirements]
2025-12-18 00:16:18.797935 | controller | skipping: Conditional result was False
2025-12-18 00:16:18.805616 | 
2025-12-18 00:16:18.805705 | TASK [ansible-test : Adjust options for unit tests]
2025-12-18 00:16:18.822114 | controller | skipping: Conditional result was False
2025-12-18 00:16:18.831738 | 
2025-12-18 00:16:18.831846 | TASK [ansible-test : Install requirements with unit tests]
2025-12-18 00:16:18.865335 | controller | ok
2025-12-18 00:16:18.910207 | 
2025-12-18 00:16:18.910343 | TASK [ansible-test : Set --skip-test]
2025-12-18 00:16:18.955411 | controller | skipping: Conditional result was False
2025-12-18 00:16:18.963207 | 
2025-12-18 00:16:18.963322 | TASK [ansible-test : Reduce verbosity]
2025-12-18 00:16:18.994484 | controller | ok
2025-12-18 00:16:19.002881 | 
2025-12-18 00:16:19.002966 | TASK [ansible-test : Setup --python option]
2025-12-18 00:16:19.027577 | controller | skipping: Conditional result was False
2025-12-18 00:16:19.037069 | 
2025-12-18 00:16:19.037182 | TASK [ansible-test : Setup --docker option]
2025-12-18 00:16:19.072558 | controller | ok
2025-12-18 00:16:19.082971 | 
2025-12-18 00:16:19.083286 | TASK [ansible-test : check if MANIFEST.json exists]
2025-12-18 00:16:20.299128 | controller | ok
2025-12-18 00:16:20.306313 | 
2025-12-18 00:16:20.306435 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2025-12-18 00:16:20.330850 | controller | skipping: Conditional result was False
2025-12-18 00:16:20.338946 | 
2025-12-18 00:16:20.339118 | TASK [ansible-test : Load information from MANIFEST.json]
2025-12-18 00:16:20.363524 | controller | skipping: Conditional result was False
2025-12-18 00:16:20.372184 | 
2025-12-18 00:16:20.372357 | TASK [ansible-test : Setup location of project for integration tests]
2025-12-18 00:16:20.397846 | controller | skipping: Conditional result was False
2025-12-18 00:16:20.406215 | 
2025-12-18 00:16:20.406353 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2025-12-18 00:16:21.679069 | controller | ok
2025-12-18 00:16:21.691612 | 
2025-12-18 00:16:21.691756 | TASK [ansible-test : Load information from galaxy.yml]
2025-12-18 00:16:21.738086 | controller | ok
2025-12-18 00:16:21.750225 | 
2025-12-18 00:16:21.750374 | TASK [ansible-test : Setup location of project for integration tests]
2025-12-18 00:16:21.796132 | controller | ok
2025-12-18 00:16:21.806088 | 
2025-12-18 00:16:21.806204 | TASK [ansible-test : Setup minimum test requirements]
2025-12-18 00:16:21.843125 | controller | ok
2025-12-18 00:16:21.851167 | 
2025-12-18 00:16:21.851295 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2025-12-18 00:16:22.829259 | controller | ok
2025-12-18 00:16:22.840325 | 
2025-12-18 00:16:22.840459 | TASK [ansible-test : Install python requirements]
2025-12-18 00:16:24.311147 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-12-18 00:16:24.329735 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2025-12-18 00:16:24.421782 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-12-18 00:16:24.429272 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2025-12-18 00:16:24.469491 | 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-12-18 00:16:24.478650 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2025-12-18 00:16:24.495794 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2025-12-18 00:16:24.571362 | controller | Installing collected packages: pathspec, yamllint
2025-12-18 00:16:24.665851 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2025-12-18 00:16:25.206169 | controller | ok: Runtime: 0:00:01.162946
2025-12-18 00:16:25.218523 | 
2025-12-18 00:16:25.218662 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2025-12-18 00:16:26.064824 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2025-12-18 00:16:26.590559 | controller | ERROR
2025-12-18 00:16:26.591009 | controller | {
2025-12-18 00:16:26.591084 | controller |   "delta": "0:00:00.009841",
2025-12-18 00:16:26.591109 | controller |   "end": "2025-12-18 00:16:26.065080",
2025-12-18 00:16:26.591130 | controller |   "msg": "non-zero return code",
2025-12-18 00:16:26.591152 | controller |   "rc": 1,
2025-12-18 00:16:26.591173 | controller |   "start": "2025-12-18 00:16:26.055239"
2025-12-18 00:16:26.591189 | controller | }
2025-12-18 00:16:26.591213 | controller | ERROR: Ignoring Errors
2025-12-18 00:16:26.598843 | 
2025-12-18 00:16:26.598980 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2025-12-18 00:16:26.974361 | controller | skipping: Conditional result was False
2025-12-18 00:16:26.981745 | 
2025-12-18 00:16:26.981859 | TASK [ansible-test : Enable persistent connection logging]
2025-12-18 00:16:28.721367 | controller | changed: section and option added
2025-12-18 00:16:28.739491 | 
2025-12-18 00:16:28.739700 | TASK [ansible-test : Set persistent ssh_type value]
2025-12-18 00:16:29.868301 | controller | changed: option added
2025-12-18 00:16:29.882228 | 
2025-12-18 00:16:29.882388 | TASK [ansible-test : Set ansible log path]
2025-12-18 00:16:30.823138 | controller | changed: section and option added
2025-12-18 00:16:30.829253 | 
2025-12-18 00:16:30.829338 | TASK [ansible-test : Identify the changed targets]
2025-12-18 00:16:31.201892 | controller | skipping: Conditional result was False
2025-12-18 00:16:31.209150 | 
2025-12-18 00:16:31.209274 | TASK [ansible-test : set_fact]
2025-12-18 00:16:31.275258 | controller | skipping: Conditional result was False
2025-12-18 00:16:31.289805 | 
2025-12-18 00:16:31.289999 | TASK [ansible-test : Set the targets]
2025-12-18 00:16:31.332141 | controller | ok
2025-12-18 00:16:31.383619 | 
2025-12-18 00:16:31.383758 | TASK [ansible-test : set_fact]
2025-12-18 00:16:31.408442 | controller | skipping: Conditional result was False
2025-12-18 00:16:31.417901 | 
2025-12-18 00:16:31.418072 | TASK [ansible-test : List the targets]
2025-12-18 00:16:31.787750 | controller | skipping: Conditional result was False
2025-12-18 00:16:31.803086 | 
2025-12-18 00:16:31.803316 | TASK [ansible-test : set_fact]
2025-12-18 00:16:31.820069 | controller | skipping: Conditional result was False
2025-12-18 00:16:31.827212 | 
2025-12-18 00:16:31.827342 | TASK [ansible-test : set_fact]
2025-12-18 00:16:31.841160 | controller | skipping: Conditional result was False
2025-12-18 00:16:31.848170 | 
2025-12-18 00:16:31.848289 | LOOP [ansible-test : set_fact]
2025-12-18 00:16:31.890093 | 
2025-12-18 00:16:31.890256 | TASK [ansible-test : set_fact]
2025-12-18 00:16:31.903734 | controller | skipping: Conditional result was False
2025-12-18 00:16:31.910998 | 
2025-12-18 00:16:31.911108 | TASK [ansible-test : debug]
2025-12-18 00:16:31.924403 | controller | skipping: Conditional result was False
2025-12-18 00:16:31.931257 | 
2025-12-18 00:16:31.931339 | TASK [ansible-test : debug]
2025-12-18 00:16:31.961850 | About to run: ansible-test sanity --requirements --docker -v
2025-12-18 00:16:31.967632 | 
2025-12-18 00:16:31.967718 | TASK [ansible-test : Run the test suite]
2025-12-18 00:16:33.523639 | controller | Run command: docker images quay.io/ansible/default-test-container:1.10.1 --format '{{json .}}'
2025-12-18 00:16:33.597362 | controller | Run command: docker pull quay.io/ansible/default-test-container:1.10.1
2025-12-18 00:16:35.504488 | controller | 1.10.1: Pulling from ansible/default-test-container
2025-12-18 00:16:36.523876 | 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-12-18 00:16:36.523950 | controller | 5667fdb72017: Pulling fs layer
2025-12-18 00:16:36.523961 | controller | d83811f270d5: Pulling fs layer
2025-12-18 00:16:36.523968 | controller | ee671aafb583: Pulling fs layer
2025-12-18 00:16:36.523977 | controller | 7fc152dfb3a6: Pulling fs layer
2025-12-18 00:16:36.523987 | controller | 3fb967a6fd03: Pulling fs layer
2025-12-18 00:16:36.523993 | controller | b3e63023ca5f: Pulling fs layer
2025-12-18 00:16:36.523999 | controller | 653cb3e65dbf: Pulling fs layer
2025-12-18 00:16:36.524004 | controller | f4712b586375: Pulling fs layer
2025-12-18 00:16:36.524010 | controller | d696ed7812f8: Pulling fs layer
2025-12-18 00:16:36.524016 | controller | 29bf28764f14: Pulling fs layer
2025-12-18 00:16:36.524022 | controller | 47d7edb3c2b7: Pulling fs layer
2025-12-18 00:16:36.524029 | controller | 07550ad4f6e4: Pulling fs layer
2025-12-18 00:16:36.524035 | controller | 7e79526e64c5: Pulling fs layer
2025-12-18 00:16:36.524041 | controller | 4fffb32882b7: Pulling fs layer
2025-12-18 00:16:36.524048 | controller | fc7a38b06f4e: Pulling fs layer
2025-12-18 00:16:36.524055 | controller | 6d9d912e060e: Pulling fs layer
2025-12-18 00:16:36.524061 | controller | 5dd542dd28d9: Pulling fs layer
2025-12-18 00:16:36.524067 | controller | cef8beb99552: Pulling fs layer
2025-12-18 00:16:36.524073 | controller | bd5557e101ad: Pulling fs layer
2025-12-18 00:16:36.524079 | controller | e057c20fabdd: Pulling fs layer
2025-12-18 00:16:36.524087 | controller | d16059cdae2e: Pulling fs layer
2025-12-18 00:16:36.524093 | controller | 431e42d906a3: Pulling fs layer
2025-12-18 00:16:36.524098 | controller | 7a0fd3b1d7c3: Pulling fs layer
2025-12-18 00:16:36.524104 | controller | aacdb6db0c8c: Pulling fs layer
2025-12-18 00:16:36.524110 | controller | 2e7f04ae8c8b: Pulling fs layer
2025-12-18 00:16:36.524116 | controller | 4cd343027a44: Pulling fs layer
2025-12-18 00:16:36.524123 | controller | 0b4379883f90: Pulling fs layer
2025-12-18 00:16:36.524129 | controller | 3d9d6d9dfc91: Pulling fs layer
2025-12-18 00:16:36.524138 | controller | 57d6314bd293: Pulling fs layer
2025-12-18 00:16:36.524144 | controller | 774036afeb7f: Pulling fs layer
2025-12-18 00:16:36.524149 | controller | 54b10f0e1645: Pulling fs layer
2025-12-18 00:16:36.524156 | controller | 78cf439d9fdb: Pulling fs layer
2025-12-18 00:16:36.524161 | controller | 36e5e20969c7: Pulling fs layer
2025-12-18 00:16:36.524167 | controller | 4b19ac3f17a8: Pulling fs layer
2025-12-18 00:16:36.524174 | controller | 7fc152dfb3a6: Waiting
2025-12-18 00:16:36.524180 | controller | 3fb967a6fd03: Waiting
2025-12-18 00:16:36.524189 | controller | b3e63023ca5f: Waiting
2025-12-18 00:16:36.524195 | controller | 653cb3e65dbf: Waiting
2025-12-18 00:16:36.524201 | controller | f4712b586375: Waiting
2025-12-18 00:16:36.524206 | controller | d696ed7812f8: Waiting
2025-12-18 00:16:36.524212 | controller | 29bf28764f14: Waiting
2025-12-18 00:16:36.524218 | controller | 47d7edb3c2b7: Waiting
2025-12-18 00:16:36.524239 | controller | 07550ad4f6e4: Waiting
2025-12-18 00:16:36.524246 | controller | 7e79526e64c5: Waiting
2025-12-18 00:16:36.524252 | controller | 4fffb32882b7: Waiting
2025-12-18 00:16:36.524257 | controller | fc7a38b06f4e: Waiting
2025-12-18 00:16:36.524263 | controller | 6d9d912e060e: Waiting
2025-12-18 00:16:36.524269 | controller | 5dd542dd28d9: Waiting
2025-12-18 00:16:36.524278 | controller | cef8beb99552: Waiting
2025-12-18 00:16:36.524284 | controller | bd5557e101ad: Waiting
2025-12-18 00:16:36.524290 | controller | e057c20fabdd: Waiting
2025-12-18 00:16:36.524296 | controller | d16059cdae2e: Waiting
2025-12-18 00:16:36.524302 | controller | 431e42d906a3: Waiting
2025-12-18 00:16:36.524308 | controller | 7a0fd3b1d7c3: Waiting
2025-12-18 00:16:36.524313 | controller | aacdb6db0c8c: Waiting
2025-12-18 00:16:36.524320 | controller | 2e7f04ae8c8b: Waiting
2025-12-18 00:16:36.524326 | controller | 4cd343027a44: Waiting
2025-12-18 00:16:36.524332 | controller | 0b4379883f90: Waiting
2025-12-18 00:16:36.524338 | controller | 3d9d6d9dfc91: Waiting
2025-12-18 00:16:36.524344 | controller | 57d6314bd293: Waiting
2025-12-18 00:16:36.524352 | controller | 774036afeb7f: Waiting
2025-12-18 00:16:36.524370 | controller | 54b10f0e1645: Waiting
2025-12-18 00:16:36.524378 | controller | 78cf439d9fdb: Waiting
2025-12-18 00:16:36.524384 | controller | 36e5e20969c7: Waiting
2025-12-18 00:16:36.524390 | controller | 4b19ac3f17a8: Waiting
2025-12-18 00:16:36.524406 | controller | ee671aafb583: Verifying Checksum
2025-12-18 00:16:36.848254 | controller | ee671aafb583: Download complete
2025-12-18 00:16:36.848308 | controller | d83811f270d5: Download complete
2025-12-18 00:16:37.610724 | controller | 7fc152dfb3a6: Verifying Checksum
2025-12-18 00:16:38.591937 | controller | 7fc152dfb3a6: Download complete
2025-12-18 00:16:38.592015 | controller | b3e63023ca5f: Verifying Checksum
2025-12-18 00:16:39.657029 | controller | b3e63023ca5f: Download complete
2025-12-18 00:16:39.657088 | controller | 653cb3e65dbf: Verifying Checksum
2025-12-18 00:16:42.924521 | controller | 653cb3e65dbf: Download complete
2025-12-18 00:16:42.924586 | controller | 5667fdb72017: Verifying Checksum
2025-12-18 00:16:43.933293 | controller | 5667fdb72017: Download complete
2025-12-18 00:16:43.933361 | controller | 3fb967a6fd03: Verifying Checksum
2025-12-18 00:16:44.207118 | controller | 3fb967a6fd03: Download complete
2025-12-18 00:16:44.207191 | controller | d696ed7812f8: Verifying Checksum
2025-12-18 00:16:44.207260 | controller | d696ed7812f8: Download complete
2025-12-18 00:16:45.085742 | controller | 29bf28764f14: Verifying Checksum
2025-12-18 00:16:45.086002 | controller | 29bf28764f14: Download complete
2025-12-18 00:16:45.708774 | controller | 5667fdb72017: Pull complete
2025-12-18 00:16:45.763012 | controller | d83811f270d5: Pull complete
2025-12-18 00:16:45.810610 | controller | ee671aafb583: Pull complete
2025-12-18 00:16:45.859495 | controller | 7fc152dfb3a6: Pull complete
2025-12-18 00:16:45.871627 | controller | 47d7edb3c2b7: Verifying Checksum
2025-12-18 00:16:45.871921 | controller | 47d7edb3c2b7: Download complete
2025-12-18 00:16:46.114260 | controller | 07550ad4f6e4: Verifying Checksum
2025-12-18 00:16:46.114425 | controller | 07550ad4f6e4: Download complete
2025-12-18 00:16:46.948109 | controller | 7e79526e64c5: Verifying Checksum
2025-12-18 00:16:46.948255 | controller | 7e79526e64c5: Download complete
2025-12-18 00:16:46.980276 | controller | 3fb967a6fd03: Pull complete
2025-12-18 00:16:47.013636 | controller | b3e63023ca5f: Pull complete
2025-12-18 00:16:47.048966 | controller | 653cb3e65dbf: Pull complete
2025-12-18 00:16:47.097934 | controller | 4fffb32882b7: Verifying Checksum
2025-12-18 00:16:47.098031 | controller | 4fffb32882b7: Download complete
2025-12-18 00:16:48.379969 | controller | 6d9d912e060e: Verifying Checksum
2025-12-18 00:16:48.380066 | controller | 6d9d912e060e: Download complete
2025-12-18 00:16:48.864673 | controller | fc7a38b06f4e: Verifying Checksum
2025-12-18 00:16:48.864830 | controller | fc7a38b06f4e: Download complete
2025-12-18 00:16:49.513675 | controller | 5dd542dd28d9: Verifying Checksum
2025-12-18 00:16:49.513897 | controller | 5dd542dd28d9: Download complete
2025-12-18 00:16:50.548069 | controller | bd5557e101ad: Verifying Checksum
2025-12-18 00:16:50.548148 | controller | bd5557e101ad: Download complete
2025-12-18 00:16:51.617615 | controller | e057c20fabdd: Verifying Checksum
2025-12-18 00:16:51.617741 | controller | e057c20fabdd: Download complete
2025-12-18 00:16:52.574657 | controller | d16059cdae2e: Verifying Checksum
2025-12-18 00:16:52.574825 | controller | d16059cdae2e: Download complete
2025-12-18 00:16:55.641773 | controller | 431e42d906a3: Verifying Checksum
2025-12-18 00:16:55.641864 | controller | 431e42d906a3: Download complete
2025-12-18 00:16:57.950605 | controller | 7a0fd3b1d7c3: Verifying Checksum
2025-12-18 00:16:57.950685 | controller | 7a0fd3b1d7c3: Download complete
2025-12-18 00:16:59.712811 | controller | aacdb6db0c8c: Verifying Checksum
2025-12-18 00:16:59.712947 | controller | aacdb6db0c8c: Download complete
2025-12-18 00:17:00.711081 | controller | 2e7f04ae8c8b: Verifying Checksum
2025-12-18 00:17:00.711264 | controller | 2e7f04ae8c8b: Download complete
2025-12-18 00:17:01.754324 | controller | 4cd343027a44: Verifying Checksum
2025-12-18 00:17:01.756328 | controller | 4cd343027a44: Download complete
2025-12-18 00:17:02.825140 | controller | 0b4379883f90: Verifying Checksum
2025-12-18 00:17:02.825266 | controller | 0b4379883f90: Download complete
2025-12-18 00:17:03.853173 | controller | 3d9d6d9dfc91: Verifying Checksum
2025-12-18 00:17:03.853351 | controller | 3d9d6d9dfc91: Download complete
2025-12-18 00:17:06.796949 | controller | f4712b586375: Download complete
2025-12-18 00:17:17.508840 | controller | 57d6314bd293: Verifying Checksum
2025-12-18 00:17:23.151949 | controller | 57d6314bd293: Download complete
2025-12-18 00:17:23.152044 | controller | cef8beb99552: Verifying Checksum
2025-12-18 00:17:24.785519 | controller | cef8beb99552: Download complete
2025-12-18 00:17:24.785626 | controller | 774036afeb7f: Verifying Checksum
2025-12-18 00:17:27.776359 | controller | 774036afeb7f: Download complete
2025-12-18 00:17:27.776416 | controller | 54b10f0e1645: Verifying Checksum
2025-12-18 00:17:32.652436 | controller | 54b10f0e1645: Download complete
2025-12-18 00:17:32.652580 | controller | 4b19ac3f17a8: Verifying Checksum
2025-12-18 00:17:32.652622 | controller | 4b19ac3f17a8: Download complete
2025-12-18 00:17:32.689043 | controller | f4712b586375: Pull complete
2025-12-18 00:17:32.732680 | controller | d696ed7812f8: Pull complete
2025-12-18 00:17:32.774443 | controller | 29bf28764f14: Pull complete
2025-12-18 00:17:32.853917 | controller | 47d7edb3c2b7: Pull complete
2025-12-18 00:17:32.882430 | controller | 07550ad4f6e4: Pull complete
2025-12-18 00:17:32.939547 | controller | 7e79526e64c5: Pull complete
2025-12-18 00:17:32.992007 | controller | 4fffb32882b7: Pull complete
2025-12-18 00:17:33.093766 | controller | fc7a38b06f4e: Pull complete
2025-12-18 00:17:33.134068 | controller | 6d9d912e060e: Pull complete
2025-12-18 00:17:33.174801 | controller | 5dd542dd28d9: Pull complete
2025-12-18 00:17:35.883811 | controller | 78cf439d9fdb: Verifying Checksum
2025-12-18 00:17:35.883962 | controller | 78cf439d9fdb: Download complete
2025-12-18 00:17:37.614736 | controller | 36e5e20969c7: Verifying Checksum
2025-12-18 00:17:37.614837 | controller | 36e5e20969c7: Download complete
2025-12-18 00:17:59.203007 | controller | cef8beb99552: Pull complete
2025-12-18 00:17:59.235768 | controller | bd5557e101ad: Pull complete
2025-12-18 00:17:59.273603 | controller | e057c20fabdd: Pull complete
2025-12-18 00:17:59.315403 | controller | d16059cdae2e: Pull complete
2025-12-18 00:18:01.646610 | controller | 431e42d906a3: Pull complete
2025-12-18 00:18:01.732639 | controller | 7a0fd3b1d7c3: Pull complete
2025-12-18 00:18:01.809014 | controller | aacdb6db0c8c: Pull complete
2025-12-18 00:18:01.854410 | controller | 2e7f04ae8c8b: Pull complete
2025-12-18 00:18:01.899614 | controller | 4cd343027a44: Pull complete
2025-12-18 00:18:01.945493 | controller | 0b4379883f90: Pull complete
2025-12-18 00:18:02.002021 | controller | 3d9d6d9dfc91: Pull complete
2025-12-18 00:18:07.771801 | controller | 57d6314bd293: Pull complete
2025-12-18 00:18:31.607604 | controller | 774036afeb7f: Pull complete
2025-12-18 00:19:04.959875 | controller | 54b10f0e1645: Pull complete
2025-12-18 00:19:32.563461 | controller | 78cf439d9fdb: Pull complete
2025-12-18 00:20:01.848821 | controller | 36e5e20969c7: Pull complete
2025-12-18 00:20:33.533743 | controller | 4b19ac3f17a8: Pull complete
2025-12-18 00:20:33.551913 | controller | Digest: sha256:954c4edffb0905df64e23c8611b1c4a62db9af13062f51cad5b658e727238d41
2025-12-18 00:20:33.557214 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:1.10.1
2025-12-18 00:20:33.567519 | controller | quay.io/ansible/default-test-container:1.10.1
2025-12-18 00:20:33.571972 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2025-12-18 00:20:33.606303 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2025-12-18 00:20:35.228559 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2025-12-18 00:20:35.228705 | controller | 04a5f4cda3ee: Pulling fs layer
2025-12-18 00:20:35.228768 | controller | ff496a88c8ed: Pulling fs layer
2025-12-18 00:20:35.228837 | controller | 0ce83f459fe7: Pulling fs layer
2025-12-18 00:20:35.228910 | controller | 2e5170e1f099: Pulling fs layer
2025-12-18 00:20:35.228976 | controller | 7641eb41b08c: Pulling fs layer
2025-12-18 00:20:35.229050 | controller | ad15fa9da398: Pulling fs layer
2025-12-18 00:20:35.229119 | controller | 087d91352424: Pulling fs layer
2025-12-18 00:20:35.229209 | controller | 8b92efd6a100: Pulling fs layer
2025-12-18 00:20:35.231893 | controller | 7641eb41b08c: Waiting
2025-12-18 00:20:35.232881 | controller | ad15fa9da398: Waiting
2025-12-18 00:20:35.232902 | controller | 087d91352424: Waiting
2025-12-18 00:20:35.232909 | controller | 8b92efd6a100: Waiting
2025-12-18 00:20:35.232920 | controller | 2e5170e1f099: Waiting
2025-12-18 00:20:35.656304 | controller | ff496a88c8ed: Verifying Checksum
2025-12-18 00:20:35.656423 | controller | ff496a88c8ed: Download complete
2025-12-18 00:20:35.771027 | controller | 0ce83f459fe7: Verifying Checksum
2025-12-18 00:20:35.771195 | controller | 0ce83f459fe7: Download complete
2025-12-18 00:20:35.972099 | controller | 04a5f4cda3ee: Verifying Checksum
2025-12-18 00:20:35.972248 | controller | 04a5f4cda3ee: Download complete
2025-12-18 00:20:36.222574 | controller | 7641eb41b08c: Download complete
2025-12-18 00:20:36.346639 | controller | 2e5170e1f099: Verifying Checksum
2025-12-18 00:20:36.374420 | controller | 2e5170e1f099: Download complete
2025-12-18 00:20:36.374476 | controller | ad15fa9da398: Verifying Checksum
2025-12-18 00:20:36.755768 | controller | ad15fa9da398: Download complete
2025-12-18 00:20:36.755900 | controller | 087d91352424: Verifying Checksum
2025-12-18 00:20:36.755991 | controller | 087d91352424: Download complete
2025-12-18 00:20:36.793785 | controller | 8b92efd6a100: Verifying Checksum
2025-12-18 00:20:36.793920 | controller | 8b92efd6a100: Download complete
2025-12-18 00:20:38.284577 | controller | 04a5f4cda3ee: Pull complete
2025-12-18 00:20:38.330069 | controller | ff496a88c8ed: Pull complete
2025-12-18 00:20:38.361334 | controller | 0ce83f459fe7: Pull complete
2025-12-18 00:20:40.397105 | controller | 2e5170e1f099: Pull complete
2025-12-18 00:20:40.421707 | controller | 7641eb41b08c: Pull complete
2025-12-18 00:20:40.469725 | controller | ad15fa9da398: Pull complete
2025-12-18 00:20:41.499935 | controller | 087d91352424: Pull complete
2025-12-18 00:20:41.528930 | controller | 8b92efd6a100: Pull complete
2025-12-18 00:20:41.559476 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2025-12-18 00:20:41.566922 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2025-12-18 00:20:41.576323 | controller | quay.io/ansible/pypi-test-container:1.0.0
2025-12-18 00:20:41.582357 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2025-12-18 00:20:52.062323 | controller | Run command: docker inspect e2d21ef322402cbb6bb139d03e204e15569c3387989fdea3907bf3c2ff2d1dae
2025-12-18 00:20:52.349268 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2025-12-18 00:20:52.367447 | controller | Including collection: ansible.netcommon (292 files)
2025-12-18 00:20:52.367530 | controller | Including collection: ansible.network (23 files)
2025-12-18 00:20:52.379863 | controller | Including collection: ansible.utils (442 files)
2025-12-18 00:20:52.426745 | controller | Including collection: arista.eos (1022 files)
2025-12-18 00:20:52.485342 | controller | Including collection: cisco.ios (1174 files)
2025-12-18 00:20:52.530382 | controller | Including collection: cisco.iosxr (1044 files)
2025-12-18 00:20:52.617251 | controller | Including collection: cisco.nxos (1682 files)
2025-12-18 00:20:52.666161 | controller | Including collection: junipernetworks.junos (963 files)
2025-12-18 00:20:52.710777 | controller | Creating a payload archive containing 8379 files...
2025-12-18 00:20:55.255315 | controller | Created a 6899005 byte payload archive containing 8379 files in 2 seconds.
2025-12-18 00:20:55.262488 | controller | Assuming Docker is available on localhost.
2025-12-18 00:20:55.263039 | 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-12-18 00:20:55.920005 | controller | Run command: docker exec -i d4a23602e1151bf08514c9c389bb84d95b033ddceabde1801d8cac0af656f25f dd of=/root/docker.sh bs=65536
2025-12-18 00:20:56.030831 | controller | Run command: docker exec d4a23602e1151bf08514c9c389bb84d95b033ddceabde1801d8cac0af656f25f /bin/bash /root/docker.sh
2025-12-18 00:20:56.133033 | controller | Run command: docker exec -i d4a23602e1151bf08514c9c389bb84d95b033ddceabde1801d8cac0af656f25f dd of=/root/ansible.tgz bs=65536
2025-12-18 00:20:56.261753 | controller | Run command: docker exec d4a23602e1151bf08514c9c389bb84d95b033ddceabde1801d8cac0af656f25f mkdir /root/ansible
2025-12-18 00:20:56.351183 | controller | Run command: docker exec d4a23602e1151bf08514c9c389bb84d95b033ddceabde1801d8cac0af656f25f tar oxzf /root/ansible.tgz -C /root/ansible
2025-12-18 00:20:57.757244 | controller | Run command: docker exec d4a23602e1151bf08514c9c389bb84d95b033ddceabde1801d8cac0af656f25f /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-i3zwvlsh.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2025-12-18 00:20:58.360729 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2025-12-18 00:20:58.360906 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2025-12-18 00:20:58.363257 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2025-12-18 00:20:58.455539 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-12-18 00:20:58.455598 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2025-12-18 00:20:58.459960 | controller | Read 0 sanity test ignore line(s) for Ansible 2.9 from: tests/sanity/ignore-2.9.txt
2025-12-18 00:20:58.469745 | controller | No tests applicable.
2025-12-18 00:20:58.469893 | controller | Running sanity test 'ansible-doc' with Python 3.6
2025-12-18 00:20:58.469909 | controller | No tests applicable.
2025-12-18 00:20:58.470031 | controller | Run command: /usr/bin/python2.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-12-18 00:20:58.560403 | controller | Running sanity test 'compile' with Python 2.6
2025-12-18 00:20:58.560571 | controller | No tests applicable.
2025-12-18 00:20:58.560795 | controller | Run command: /usr/bin/python2.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-12-18 00:20:58.793925 | controller | Running sanity test 'compile' with Python 2.7
2025-12-18 00:20:58.794131 | controller | No tests applicable.
2025-12-18 00:20:58.794161 | controller | Run command: /usr/bin/python3.5 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-12-18 00:20:58.941071 | controller | Running sanity test 'compile' with Python 3.5
2025-12-18 00:20:58.941243 | controller | No tests applicable.
2025-12-18 00:20:58.941363 | controller | Running sanity test 'compile' with Python 3.6
2025-12-18 00:20:58.941377 | controller | No tests applicable.
2025-12-18 00:20:58.941614 | controller | Run command: /usr/bin/python3.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-12-18 00:20:59.057297 | controller | Running sanity test 'compile' with Python 3.7
2025-12-18 00:20:59.057543 | controller | No tests applicable.
2025-12-18 00:20:59.057747 | controller | Run command: /usr/bin/python3.8 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-12-18 00:20:59.183514 | controller | Running sanity test 'compile' with Python 3.8
2025-12-18 00:20:59.183757 | controller | No tests applicable.
2025-12-18 00:20:59.183865 | controller | Running sanity test 'empty-init' with Python 3.6
2025-12-18 00:20:59.183879 | controller | No tests applicable.
2025-12-18 00:20:59.183988 | controller | Running sanity test 'future-import-boilerplate' with Python 3.6
2025-12-18 00:20:59.184003 | controller | No tests applicable.
2025-12-18 00:20:59.185099 | controller | Running sanity test 'ignores'
2025-12-18 00:20:59.185131 | 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-12-18 00:21:00.044903 | controller | Ignoring packaging: markers 'python_version < "3.6"' don't match your environment
2025-12-18 00:21:00.050697 | controller | Ignoring coverage: markers 'python_version > "3.7"' don't match your environment
2025-12-18 00:21:00.052004 | controller | Ignoring cryptography: markers 'python_version < "2.7"' don't match your environment
2025-12-18 00:21:00.056692 | controller | Ignoring deepdiff: markers 'python_version < "3"' don't match your environment
2025-12-18 00:21:00.060114 | controller | Ignoring jinja2: markers 'python_version < "2.7"' don't match your environment
2025-12-18 00:21:00.060149 | controller | Ignoring urllib3: markers 'python_version < "2.7"' don't match your environment
2025-12-18 00:21:00.061720 | controller | Ignoring sphinx: markers 'python_version < "2.7"' don't match your environment
2025-12-18 00:21:00.069386 | controller | Ignoring wheel: markers 'python_version < "2.7"' don't match your environment
2025-12-18 00:21:00.070739 | controller | Ignoring yamllint: markers 'python_version < "2.7"' don't match your environment
2025-12-18 00:21:00.075344 | controller | Ignoring paramiko: markers 'python_version < "2.7"' don't match your environment
2025-12-18 00:21:00.077796 | controller | Ignoring pytest: markers 'python_version < "2.7"' don't match your environment
2025-12-18 00:21:00.080538 | controller | Ignoring pytest: markers 'python_version == "2.7"' don't match your environment
2025-12-18 00:21:00.081925 | controller | Ignoring pytest-forked: markers 'python_version < "2.7"' don't match your environment
2025-12-18 00:21:00.088114 | controller | Ignoring requests: markers 'python_version < "2.7"' don't match your environment
2025-12-18 00:21:00.096109 | controller | Ignoring virtualenv: markers 'python_version < "2.7"' don't match your environment
2025-12-18 00:21:00.098800 | controller | Ignoring pathspec: markers 'python_version < "2.7"' don't match your environment
2025-12-18 00:21:00.100032 | controller | Ignoring pyopenssl: markers 'python_version < "2.7"' don't match your environment
2025-12-18 00:21:00.104091 | controller | Ignoring pyyaml: markers 'python_version < "2.7"' don't match your environment
2025-12-18 00:21:00.105153 | controller | Ignoring pycparser: markers 'python_version < "2.7"' don't match your environment
2025-12-18 00:21:00.112402 | controller | Ignoring xmltodict: markers 'python_version < "2.7"' don't match your environment
2025-12-18 00:21:00.114215 | controller | Ignoring lxml: markers 'python_version < "2.7"' don't match your environment
2025-12-18 00:21:00.117351 | controller | Ignoring pyvmomi: markers 'python_version < "2.7"' don't match your environment
2025-12-18 00:21:00.119655 | controller | Ignoring MarkupSafe: markers 'python_version < "3.6"' don't match your environment
2025-12-18 00:21:00.127630 | controller | Ignoring setuptools: markers 'python_version <= "2.7"' don't match your environment
2025-12-18 00:21:00.135036 | controller | Ignoring websocket-client: markers 'python_version < "3"' don't match your environment
2025-12-18 00:21:00.137020 | controller | Ignoring certifi: markers 'python_version < "3"' don't match your environment
2025-12-18 00:21:00.157715 | controller | Looking in indexes: http://172.17.0.2:3141/root/pypi/+simple/
2025-12-18 00:21:00.159050 | 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-12-18 00:21:00.195499 | 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-12-18 00:21:00.200065 | 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-12-18 00:21:00.205960 | 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-12-18 00:21:00.211248 | 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-12-18 00:21:00.218082 | 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-12-18 00:21:00.223724 | 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-12-18 00:21:00.229840 | 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-12-18 00:21:00.232123 | 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-12-18 00:21:00.255048 | 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-12-18 00:21:00.259015 | 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-12-18 00:21:00.263555 | 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-12-18 00:21:00.266501 | 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-12-18 00:21:00.268763 | 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-12-18 00:21:00.273943 | controller | Collecting isort==4.3.16 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 55))
2025-12-18 00:21:15.296199 | 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-12-18 00:21:30.814278 | 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-12-18 00:21:46.832311 | 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-12-18 00:22:03.838753 | 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-12-18 00:22:22.858548 | 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-12-18 00:22:37.879088 | 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-12-18 00:22:38.009068 | 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-12-18 00:22:38.009126 | 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-12-18 00:22:38.009256 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2025-12-18 00:22:38.009396 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2025-12-18 00:22:38.010992 | controller | Removing custom PyPI hosts entries: /etc/hosts
2025-12-18 00:22:38.052840 | controller | Run command: docker exec d4a23602e1151bf08514c9c389bb84d95b033ddceabde1801d8cac0af656f25f tar czf /root/output.tgz --exclude .tmp -C /root/ansible/ansible_collections/ansible/network/tests output
2025-12-18 00:22:38.148645 | controller | Run command: docker exec -i d4a23602e1151bf08514c9c389bb84d95b033ddceabde1801d8cac0af656f25f dd if=/root/output.tgz bs=65536
2025-12-18 00:22:38.221796 | controller | Run command: tar oxzf /tmp/ansible-result-43y67b2b.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2025-12-18 00:22:38.235272 | controller | Run command: docker rm -f e2d21ef322402cbb6bb139d03e204e15569c3387989fdea3907bf3c2ff2d1dae
2025-12-18 00:22:38.495139 | controller | Run command: docker rm -f d4a23602e1151bf08514c9c389bb84d95b033ddceabde1801d8cac0af656f25f
2025-12-18 00:22:39.329945 | controller | ERROR: Command "docker exec d4a23602e1151bf08514c9c389bb84d95b033ddceabde1801d8cac0af656f25f /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-i3zwvlsh.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2025-12-18 00:22:39.609841 | controller | ERROR
2025-12-18 00:22:39.610174 | controller | {
2025-12-18 00:22:39.610242 | controller |   "delta": "0:06:06.358922",
2025-12-18 00:22:39.610286 | controller |   "end": "2025-12-18 00:22:39.391520",
2025-12-18 00:22:39.610319 | controller |   "msg": "non-zero return code",
2025-12-18 00:22:39.610363 | controller |   "rc": 1,
2025-12-18 00:22:39.610400 | controller |   "start": "2025-12-18 00:16:33.032598"
2025-12-18 00:22:39.610426 | controller | }
failure
2025-12-18 00:22:39.618915 | 
2025-12-18 00:22:39.619084 | PLAY RECAP
2025-12-18 00:22:39.619151 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2025-12-18 00:22:39.619182 | 
2025-12-18 00:22:39.839344 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-12-18 00:22:39.841227 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-18 00:22:40.507730 | 
2025-12-18 00:22:40.507863 | PLAY [all]
2025-12-18 00:22:40.533178 | 
2025-12-18 00:22:40.533334 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-12-18 00:22:43.583177 | controller | changed: non-zero return code
2025-12-18 00:22:43.598268 | 
2025-12-18 00:22:43.598483 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-12-18 00:22:43.617251 | controller | skipping: Conditional result was False
2025-12-18 00:22:43.633334 | 
2025-12-18 00:22:43.633516 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-12-18 00:22:43.667741 | 
2025-12-18 00:22:43.668070 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-12-18 00:22:43.692587 | 
2025-12-18 00:22:43.692780 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-12-18 00:22:43.707836 | controller | skipping: Conditional result was False
2025-12-18 00:22:43.717006 | 
2025-12-18 00:22:43.717130 | LOOP [fetch-subunit-output : Generate subunit file]
2025-12-18 00:22:43.738925 | 
2025-12-18 00:22:43.739102 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-12-18 00:22:43.753448 | controller | skipping: Conditional result was False
2025-12-18 00:22:43.761748 | 
2025-12-18 00:22:43.761878 | TASK [fetch-subunit-output : Remove the temporary file]
2025-12-18 00:22:43.775906 | controller | skipping: Conditional result was False
2025-12-18 00:22:43.782418 | 
2025-12-18 00:22:43.782496 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-12-18 00:22:43.806416 | controller | skipping: Conditional result was False
2025-12-18 00:22:43.839366 | 
2025-12-18 00:22:43.839461 | PLAY RECAP
2025-12-18 00:22:43.839549 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-18 00:22:43.839572 | 
2025-12-18 00:22:43.953157 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-18 00:22:43.954099 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-12-18 00:22:44.567147 | 
2025-12-18 00:22:44.567277 | PLAY [all:!appliance*]
2025-12-18 00:22:44.592162 | 
2025-12-18 00:22:44.592295 | TASK [unregister the node]
2025-12-18 00:22:44.949927 | controller | skipping: Conditional result was False
2025-12-18 00:22:44.957007 | 
2025-12-18 00:22:44.957593 | TASK [include_role : fetch-output]
2025-12-18 00:22:44.999094 | controller | ok
2025-12-18 00:22:45.024215 | 
2025-12-18 00:22:45.024352 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-18 00:22:45.111085 | controller | skipping: Conditional result was False
2025-12-18 00:22:45.119115 | 
2025-12-18 00:22:45.119253 | TASK [fetch-output : Set log path for single node]
2025-12-18 00:22:45.161384 | controller | ok
2025-12-18 00:22:45.168181 | 
2025-12-18 00:22:45.168296 | LOOP [fetch-output : Ensure local output dirs]
2025-12-18 00:22:45.625729 | controller -> localhost | ok: "/var/lib/zuul/builds/38de3f2da0724dbfbdcee3e54f415b8d/work/logs"
2025-12-18 00:22:45.900350 | controller -> localhost | changed: "/var/lib/zuul/builds/38de3f2da0724dbfbdcee3e54f415b8d/work/artifacts"
2025-12-18 00:22:46.141001 | controller -> localhost | changed: "/var/lib/zuul/builds/38de3f2da0724dbfbdcee3e54f415b8d/work/docs"
2025-12-18 00:22:46.160809 | 
2025-12-18 00:22:46.160975 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-18 00:22:48.406791 | controller | changed:
2025-12-18 00:22:48.407099 | controller | .d..t...... ./
2025-12-18 00:22:48.407142 | controller | cd+++++++++ controller/
2025-12-18 00:22:48.407180 | controller | changed: All items complete
2025-12-18 00:22:48.407202 | 
2025-12-18 00:22:50.448175 | controller | changed: .d..t...... ./
2025-12-18 00:22:52.496937 | controller | changed: .d..t...... ./
2025-12-18 00:22:52.514489 | 
2025-12-18 00:22:52.514664 | TASK [include_role : fetch-output-openshift]
2025-12-18 00:22:52.531874 | controller | skipping: Conditional result was False
2025-12-18 00:22:52.542566 | 
2025-12-18 00:22:52.542701 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-18 00:22:52.571538 | controller | skipping: Conditional result was False
2025-12-18 00:22:52.581876 | controller | skipping: Conditional result was False
2025-12-18 00:22:52.614364 | 
2025-12-18 00:22:52.614506 | PLAY [localhost]
2025-12-18 00:22:52.628267 | 
2025-12-18 00:22:52.628369 | TASK [Run Zuul manifest role]
2025-12-18 00:22:52.647067 | localhost | ok
2025-12-18 00:22:52.661427 | 
2025-12-18 00:22:52.661535 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-18 00:22:53.027302 | localhost | changed
2025-12-18 00:22:53.032955 | 
2025-12-18 00:22:53.033051 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-18 00:22:53.062824 | localhost | ok
2025-12-18 00:22:53.071475 | 
2025-12-18 00:22:53.071600 | TASK [Set zuul-log-path fact]
2025-12-18 00:22:53.091061 | localhost | ok
2025-12-18 00:22:53.132828 | 
2025-12-18 00:22:53.132948 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-18 00:22:53.163665 | localhost | ok
2025-12-18 00:22:53.172795 | 
2025-12-18 00:22:53.172907 | LOOP [Run upload-logs-swift role]
2025-12-18 00:22:53.209394 | localhost | Output suppressed because no_log was given
2025-12-18 00:22:53.238725 | 
2025-12-18 00:22:53.238853 | TASK [Set zuul-log-path fact]
2025-12-18 00:22:53.263346 | localhost | skipping: Conditional result was False
2025-12-18 00:22:53.270136 | 
2025-12-18 00:22:53.270250 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-12-18 00:22:53.650640 | localhost -> localhost | ok: Runtime: 0:00:00.007835
2025-12-18 00:22:53.657456 | 
2025-12-18 00:22:53.657619 | TASK [upload-logs-swift : Upload logs to swift]