2025-12-01 00:21:33.467708 | Job console starting...
2025-12-01 00:21:33.481281 | Updating repositories
2025-12-01 00:21:33.615151 | Preparing job workspace
2025-12-01 00:21:43.147692 | Running Ansible setup...
2025-12-01 00:21:50.797153 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-12-01 00:21:51.489946 | 
2025-12-01 00:21:51.490112 | PLAY [localhost]
2025-12-01 00:21:51.500154 | 
2025-12-01 00:21:51.500292 | TASK [Gathering Facts]
2025-12-01 00:21:52.540414 | localhost | ok
2025-12-01 00:21:52.564970 | 
2025-12-01 00:21:52.565184 | TASK [Setup log path fact]
2025-12-01 00:21:52.584984 | localhost | ok
2025-12-01 00:21:52.599358 | 
2025-12-01 00:21:52.599451 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-01 00:21:52.638374 | localhost | ok
2025-12-01 00:21:52.646669 | 
2025-12-01 00:21:52.646771 | TASK [emit-job-header : Print job information]
2025-12-01 00:21:52.674977 | # Job Information
2025-12-01 00:21:52.675153 | Ansible Version: 2.15.12
2025-12-01 00:21:52.675185 | Job: ansible-test-sanity-docker-stable-2.10
2025-12-01 00:21:52.675206 | Pipeline: periodic
2025-12-01 00:21:52.675225 | Executor: ze01.softwarefactory-project.io
2025-12-01 00:21:52.675243 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-12-01 00:21:52.675263 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/f12/ansible/f12e4f52245a46c9aa9ee866168f732b/
2025-12-01 00:21:52.675282 | Event ID: 7f239586727e4cf3b26e006c8a5b9dc9
2025-12-01 00:21:52.678980 | 
2025-12-01 00:21:52.679059 | LOOP [emit-job-header : Print node information]
2025-12-01 00:21:52.809857 | localhost | ok:
2025-12-01 00:21:52.810195 | localhost | # Node Information
2025-12-01 00:21:52.810264 | localhost | Inventory Hostname: controller
2025-12-01 00:21:52.810312 | localhost | Hostname: np0005540483
2025-12-01 00:21:52.810362 | localhost | Username: zuul
2025-12-01 00:21:52.810414 | localhost | Distro: Fedora 37
2025-12-01 00:21:52.810457 | localhost | Provider: ansible-vexxhost-ams1
2025-12-01 00:21:52.810498 | localhost | Region: ams1
2025-12-01 00:21:52.810538 | localhost | Label: ansible-fedora-37-1vcpu
2025-12-01 00:21:52.810578 | localhost | Product Name: OpenStack Nova
2025-12-01 00:21:52.810617 | localhost | Interface IP: 38.129.16.132
2025-12-01 00:21:52.837857 | 
2025-12-01 00:21:52.838040 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-01 00:21:53.287410 | localhost -> localhost | changed
2025-12-01 00:21:53.295176 | 
2025-12-01 00:21:53.295302 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-01 00:21:54.104421 | localhost -> localhost | changed
2025-12-01 00:21:54.131840 | 
2025-12-01 00:21:54.131919 | PLAY [all:!appliance*]
2025-12-01 00:21:54.147818 | 
2025-12-01 00:21:54.147889 | TASK [include_role : start-zuul-console]
2025-12-01 00:21:54.168104 | controller | ok
2025-12-01 00:21:54.185514 | 
2025-12-01 00:21:54.185602 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-01 00:21:55.177943 | controller | ok
2025-12-01 00:21:55.195520 | 
2025-12-01 00:21:55.195657 | TASK [use-our-mirror : Retrieve the IP address]
2025-12-01 00:21:57.372238 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-12-01 00:21:57.390285 | 
2025-12-01 00:21:57.390462 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-12-01 00:21:57.776660 | controller | skipping: Conditional result was False
2025-12-01 00:21:57.787116 | 
2025-12-01 00:21:57.787249 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-12-01 00:21:57.823594 | controller | skipping: Conditional result was False
2025-12-01 00:21:57.838848 | 
2025-12-01 00:21:57.839010 | TASK [use-our-mirror : Create the podman configuration directory]
2025-12-01 00:21:57.876610 | controller | skipping: Conditional result was False
2025-12-01 00:21:57.892573 | 
2025-12-01 00:21:57.892754 | TASK [use-our-mirror : Copy the podman configuration]
2025-12-01 00:21:57.920549 | controller | skipping: Conditional result was False
2025-12-01 00:21:57.929934 | 
2025-12-01 00:21:57.930071 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-12-01 00:21:57.954817 | controller | skipping: Conditional result was False
2025-12-01 00:21:57.962998 | 
2025-12-01 00:21:57.963115 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-12-01 00:21:57.997749 | controller | skipping: Conditional result was False
2025-12-01 00:21:58.012582 | 
2025-12-01 00:21:58.012687 | TASK [Disable Fedora Modular]
2025-12-01 00:21:59.110817 | controller | changed
2025-12-01 00:21:59.124812 | 
2025-12-01 00:21:59.125102 | TASK [Enable EPEL]
2025-12-01 00:21:59.151650 | controller | skipping: Conditional result was False
2025-12-01 00:21:59.160827 | 
2025-12-01 00:21:59.161252 | TASK [Register the RHEL node]
2025-12-01 00:21:59.579872 | 
2025-12-01 00:21:59.580057 | TASK [Show the subscription-manager status]
2025-12-01 00:21:59.943587 | controller | skipping: Conditional result was False
2025-12-01 00:21:59.950357 | 
2025-12-01 00:21:59.950425 | TASK [Enable EPEL on RHEL]
2025-12-01 00:22:00.351139 | controller | skipping: Conditional result was False
2025-12-01 00:22:00.367227 | 
2025-12-01 00:22:00.367367 | TASK [Install git and tox]
2025-12-01 00:23:50.678595 | controller | changed
2025-12-01 00:23:50.687956 | 
2025-12-01 00:23:50.688125 | TASK [include_role : prepare-workspace]
2025-12-01 00:23:50.722228 | controller | ok
2025-12-01 00:23:50.750322 | 
2025-12-01 00:23:50.750440 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-01 00:23:51.588206 | controller | ok
2025-12-01 00:23:51.602412 | 
2025-12-01 00:23:51.602570 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-01 00:24:06.787629 | controller | Output suppressed because no_log was given
2025-12-01 00:24:06.798818 | 
2025-12-01 00:24:06.798943 | TASK [include_role : prepare-workspace-openshift]
2025-12-01 00:24:06.822843 | controller | skipping: Conditional result was False
2025-12-01 00:24:06.846104 | 
2025-12-01 00:24:06.846190 | PLAY [all:!appliance]
2025-12-01 00:24:06.863843 | 
2025-12-01 00:24:06.863948 | TASK [Run add-build-sshkey role (RSA)]
2025-12-01 00:24:06.893221 | controller | ok
2025-12-01 00:24:06.908371 | 
2025-12-01 00:24:06.908486 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-01 00:24:07.168851 | controller -> localhost | ok
2025-12-01 00:24:07.179697 | 
2025-12-01 00:24:07.179830 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-01 00:24:07.208538 | controller | ok
2025-12-01 00:24:07.233840 | controller | included: /var/lib/zuul/builds/f12e4f52245a46c9aa9ee866168f732b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-01 00:24:07.242152 | 
2025-12-01 00:24:07.242265 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-01 00:24:07.710147 | controller -> localhost | Generating public/private rsa key pair.
2025-12-01 00:24:07.710382 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/f12e4f52245a46c9aa9ee866168f732b/work/f12e4f52245a46c9aa9ee866168f732b_id_rsa.
2025-12-01 00:24:07.710422 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/f12e4f52245a46c9aa9ee866168f732b/work/f12e4f52245a46c9aa9ee866168f732b_id_rsa.pub.
2025-12-01 00:24:07.710453 | controller -> localhost | The key fingerprint is:
2025-12-01 00:24:07.710482 | controller -> localhost | SHA256:a5gZiMUS5SL2n6eyBLbC47ufQqm9IxB9H29xdZRgiWI zuul-build-sshkey
2025-12-01 00:24:07.710511 | controller -> localhost | The key's randomart image is:
2025-12-01 00:24:07.710539 | controller -> localhost | +---[RSA 2048]----+
2025-12-01 00:24:07.710567 | controller -> localhost | |  ...      .o+.. |
2025-12-01 00:24:07.710596 | controller -> localhost | |   +    E ..o o  |
2025-12-01 00:24:07.710623 | controller -> localhost | |.oo +  . . . .   |
2025-12-01 00:24:07.710650 | controller -> localhost | |o.o=... . .      |
2025-12-01 00:24:07.710676 | controller -> localhost | | +oo...oSo       |
2025-12-01 00:24:07.710715 | controller -> localhost | |+oo . o=o.       |
2025-12-01 00:24:07.710766 | controller -> localhost | |== . o+oo        |
2025-12-01 00:24:07.710815 | controller -> localhost | |=.=.. o.         |
2025-12-01 00:24:07.710843 | controller -> localhost | | =**o.           |
2025-12-01 00:24:07.710871 | controller -> localhost | +----[SHA256]-----+
2025-12-01 00:24:07.710932 | controller -> localhost | ok: Runtime: 0:00:00.035212
2025-12-01 00:24:07.724352 | 
2025-12-01 00:24:07.724496 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-01 00:24:07.754129 | controller | ok
2025-12-01 00:24:07.765268 | controller | included: /var/lib/zuul/builds/f12e4f52245a46c9aa9ee866168f732b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-01 00:24:07.774400 | 
2025-12-01 00:24:07.774471 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-01 00:24:07.798320 | controller | skipping: Conditional result was False
2025-12-01 00:24:07.804455 | 
2025-12-01 00:24:07.804517 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-01 00:24:08.906190 | controller | changed
2025-12-01 00:24:08.920845 | 
2025-12-01 00:24:08.921073 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-01 00:24:09.557608 | controller | ok
2025-12-01 00:24:09.564379 | 
2025-12-01 00:24:09.564447 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-01 00:24:12.480350 | controller | changed
2025-12-01 00:24:12.492522 | 
2025-12-01 00:24:12.492656 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-01 00:24:15.479633 | controller | changed
2025-12-01 00:24:15.493341 | 
2025-12-01 00:24:15.493488 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-01 00:24:15.520323 | controller | skipping: Conditional result was False
2025-12-01 00:24:15.535145 | 
2025-12-01 00:24:15.535347 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-01 00:24:15.940256 | controller -> localhost | changed
2025-12-01 00:24:15.955631 | 
2025-12-01 00:24:15.955732 | TASK [add-build-sshkey : Add back temp key]
2025-12-01 00:24:16.251360 | controller -> localhost | Identity added: /var/lib/zuul/builds/f12e4f52245a46c9aa9ee866168f732b/work/f12e4f52245a46c9aa9ee866168f732b_id_rsa (zuul-build-sshkey)
2025-12-01 00:24:16.251809 | controller -> localhost | ok: Runtime: 0:00:00.013140
2025-12-01 00:24:16.271702 | 
2025-12-01 00:24:16.271807 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-01 00:24:17.159034 | controller | ok
2025-12-01 00:24:17.194633 | 
2025-12-01 00:24:17.194768 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-01 00:24:17.240240 | controller | skipping: Conditional result was False
2025-12-01 00:24:17.251510 | 
2025-12-01 00:24:17.251600 | TASK [Run add-build-sshkey role (ECDSA)]
2025-12-01 00:24:17.284600 | controller | ok
2025-12-01 00:24:17.308341 | 
2025-12-01 00:24:17.308469 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-01 00:24:17.587254 | controller -> localhost | ok
2025-12-01 00:24:17.593873 | 
2025-12-01 00:24:17.593959 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-01 00:24:17.624170 | controller | ok
2025-12-01 00:24:17.635134 | controller | included: /var/lib/zuul/builds/f12e4f52245a46c9aa9ee866168f732b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-01 00:24:17.641564 | 
2025-12-01 00:24:17.641661 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-01 00:24:17.977864 | controller -> localhost | Generating public/private ecdsa key pair.
2025-12-01 00:24:17.978266 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/f12e4f52245a46c9aa9ee866168f732b/work/f12e4f52245a46c9aa9ee866168f732b_id_ecdsa.
2025-12-01 00:24:17.978366 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/f12e4f52245a46c9aa9ee866168f732b/work/f12e4f52245a46c9aa9ee866168f732b_id_ecdsa.pub.
2025-12-01 00:24:17.978467 | controller -> localhost | The key fingerprint is:
2025-12-01 00:24:17.978537 | controller -> localhost | SHA256:BHArun7aqBSoXaSCsMGgpSM0SjcE6wBO/7PiURBNM14 zuul-build-sshkey
2025-12-01 00:24:17.978584 | controller -> localhost | The key's randomart image is:
2025-12-01 00:24:17.978629 | controller -> localhost | +---[ECDSA 521]---+
2025-12-01 00:24:17.978670 | controller -> localhost | |o*+=+=oE         |
2025-12-01 00:24:17.978711 | controller -> localhost | |@o= +o+o         |
2025-12-01 00:24:17.978751 | controller -> localhost | |X+ oo.. .        |
2025-12-01 00:24:17.978792 | controller -> localhost | |B+ +o. .         |
2025-12-01 00:24:17.978890 | controller -> localhost | |=oo .+  S        |
2025-12-01 00:24:17.978967 | controller -> localhost | |.o.o. o          |
2025-12-01 00:24:17.979059 | controller -> localhost | |..oo .           |
2025-12-01 00:24:17.979117 | controller -> localhost | |...o+            |
2025-12-01 00:24:17.979158 | controller -> localhost | |..++.            |
2025-12-01 00:24:17.979197 | controller -> localhost | +----[SHA256]-----+
2025-12-01 00:24:17.979287 | controller -> localhost | ok: Runtime: 0:00:00.010777
2025-12-01 00:24:18.001620 | 
2025-12-01 00:24:18.001897 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-01 00:24:18.028473 | controller | ok
2025-12-01 00:24:18.040397 | controller | included: /var/lib/zuul/builds/f12e4f52245a46c9aa9ee866168f732b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-01 00:24:18.060685 | 
2025-12-01 00:24:18.060881 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-01 00:24:18.087642 | controller | skipping: Conditional result was False
2025-12-01 00:24:18.101706 | 
2025-12-01 00:24:18.101855 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-01 00:24:18.977355 | controller | changed
2025-12-01 00:24:18.991969 | 
2025-12-01 00:24:18.992158 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-01 00:24:19.636698 | controller | ok
2025-12-01 00:24:19.651090 | 
2025-12-01 00:24:19.651280 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-01 00:24:22.573507 | controller | changed
2025-12-01 00:24:22.589081 | 
2025-12-01 00:24:22.589220 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-01 00:24:25.486147 | controller | changed
2025-12-01 00:24:25.496610 | 
2025-12-01 00:24:25.496775 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-01 00:24:25.522273 | controller | skipping: Conditional result was False
2025-12-01 00:24:25.531755 | 
2025-12-01 00:24:25.531879 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-01 00:24:25.780372 | controller -> localhost | changed
2025-12-01 00:24:25.792259 | 
2025-12-01 00:24:25.792363 | TASK [add-build-sshkey : Add back temp key]
2025-12-01 00:24:26.061408 | controller -> localhost | Identity added: /var/lib/zuul/builds/f12e4f52245a46c9aa9ee866168f732b/work/f12e4f52245a46c9aa9ee866168f732b_id_ecdsa (zuul-build-sshkey)
2025-12-01 00:24:26.061682 | controller -> localhost | ok: Runtime: 0:00:00.009393
2025-12-01 00:24:26.068592 | 
2025-12-01 00:24:26.068664 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-01 00:24:26.719883 | controller | ok
2025-12-01 00:24:26.726293 | 
2025-12-01 00:24:26.726404 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-01 00:24:26.751232 | controller | skipping: Conditional result was False
2025-12-01 00:24:26.768699 | 
2025-12-01 00:24:26.768841 | TASK [include_role : remove-zuul-sshkey]
2025-12-01 00:24:26.794383 | controller | skipping: Conditional result was False
2025-12-01 00:24:26.804938 | 
2025-12-01 00:24:26.805136 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-01 00:24:27.424139 | controller | ok: "logs"
2025-12-01 00:24:27.424416 | controller | ok: All items complete
2025-12-01 00:24:27.424450 | 
2025-12-01 00:24:28.004165 | controller | ok: "artifacts"
2025-12-01 00:24:28.593355 | controller | ok: "docs"
2025-12-01 00:24:28.610622 | 
2025-12-01 00:24:28.610789 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-01 00:24:29.240104 | controller | changed: "logs"
2025-12-01 00:24:29.829695 | controller | changed: "artifacts"
2025-12-01 00:24:30.412678 | controller | changed: "docs"
2025-12-01 00:24:30.473817 | 
2025-12-01 00:24:30.473953 | PLAY RECAP
2025-12-01 00:24:30.474095 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-12-01 00:24:30.474163 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-01 00:24:30.474206 | 
2025-12-01 00:24:30.642183 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-12-01 00:24:30.642975 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-01 00:24:31.196060 | 
2025-12-01 00:24:31.196228 | PLAY [all]
2025-12-01 00:24:31.243817 | 
2025-12-01 00:24:31.243955 | TASK [Install binary dependencies]
2025-12-01 00:24:31.301561 | controller | ok
2025-12-01 00:24:31.321979 | 
2025-12-01 00:24:31.322138 | TASK [bindep : Include find tasks]
2025-12-01 00:24:31.351966 | controller | ok
2025-12-01 00:24:31.360588 | controller | included: /var/lib/zuul/builds/f12e4f52245a46c9aa9ee866168f732b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-12-01 00:24:31.367087 | 
2025-12-01 00:24:31.367160 | TASK [bindep : Look for bindep.txt]
2025-12-01 00:24:32.268362 | controller | ok
2025-12-01 00:24:32.278544 | 
2025-12-01 00:24:32.278655 | TASK [bindep : Define bindep_file fact]
2025-12-01 00:24:32.305362 | controller | skipping: Conditional result was False
2025-12-01 00:24:32.315946 | 
2025-12-01 00:24:32.316113 | TASK [bindep : Look for other-requirements.txt]
2025-12-01 00:24:32.928575 | controller | ok
2025-12-01 00:24:32.943720 | 
2025-12-01 00:24:32.943891 | TASK [bindep : Define bindep_file fact]
2025-12-01 00:24:32.981822 | controller | skipping: Conditional result was False
2025-12-01 00:24:32.996549 | 
2025-12-01 00:24:32.996737 | TASK [bindep : Look for bindep fallback file]
2025-12-01 00:24:33.035809 | controller | skipping: Conditional result was False
2025-12-01 00:24:33.051582 | 
2025-12-01 00:24:33.051792 | TASK [bindep : Define bindep_file fact]
2025-12-01 00:24:33.077592 | controller | skipping: Conditional result was False
2025-12-01 00:24:33.087616 | 
2025-12-01 00:24:33.087741 | TASK [bindep : Include bindep tasks]
2025-12-01 00:24:33.113364 | controller | skipping: Conditional result was False
2025-12-01 00:24:33.125182 | 
2025-12-01 00:24:33.125902 | TASK [bindep : Include install tasks]
2025-12-01 00:24:33.152201 | controller | skipping: Conditional result was False
2025-12-01 00:24:33.159074 | 
2025-12-01 00:24:33.159150 | LOOP [bindep : Include package tasks]
2025-12-01 00:24:33.225998 | 
2025-12-01 00:24:33.226218 | TASK [Run test-setup role]
2025-12-01 00:24:33.244925 | controller | ok
2025-12-01 00:24:33.263309 | 
2025-12-01 00:24:33.263437 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-12-01 00:24:33.937710 | controller | ok
2025-12-01 00:24:33.947562 | 
2025-12-01 00:24:33.947646 | TASK [test-setup : Run tools/test-setup.sh]
2025-12-01 00:24:34.307242 | controller | skipping: Conditional result was False
2025-12-01 00:24:34.332777 | 
2025-12-01 00:24:34.332885 | PLAY RECAP
2025-12-01 00:24:34.332926 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-01 00:24:34.332947 | 
2025-12-01 00:24:34.450163 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-01 00:24:34.452088 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-12-01 00:24:35.095140 | 
2025-12-01 00:24:35.095281 | PLAY [controller]
2025-12-01 00:24:35.126113 | 
2025-12-01 00:24:35.126245 | TASK [Create the /root directory]
2025-12-01 00:24:36.085854 | controller | ok
2025-12-01 00:24:36.092068 | 
2025-12-01 00:24:36.092191 | TASK [Install glibc-langpack-en]
2025-12-01 00:24:43.147587 | controller | ok: Nothing to do
2025-12-01 00:24:43.162316 | 
2025-12-01 00:24:43.162478 | TASK [Ensure controller directory exists]
2025-12-01 00:24:43.983469 | controller | changed
2025-12-01 00:24:43.996021 | 
2025-12-01 00:24:43.996237 | TASK [Install container runtime]
2025-12-01 00:24:44.066006 | controller | ok
2025-12-01 00:24:44.135727 | 
2025-12-01 00:24:44.136056 | LOOP [ensure-docker : Gather variables for each operating system]
2025-12-01 00:24:44.176432 | controller | ok: "/var/lib/zuul/builds/f12e4f52245a46c9aa9ee866168f732b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-12-01 00:24:44.189264 | 
2025-12-01 00:24:44.189392 | TASK [ensure-docker : Sanity check for distro selection]
2025-12-01 00:24:44.205159 | controller | skipping: Conditional result was False
2025-12-01 00:24:44.215110 | 
2025-12-01 00:24:44.215209 | TASK [ensure-docker : Sanity check for distro install]
2025-12-01 00:24:44.242338 | controller | skipping: Conditional result was False
2025-12-01 00:24:44.250914 | 
2025-12-01 00:24:44.251021 | TASK [ensure-docker : Sanity check for upstream install]
2025-12-01 00:24:44.277121 | controller | skipping: Conditional result was False
2025-12-01 00:24:44.286764 | 
2025-12-01 00:24:44.286896 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-12-01 00:24:44.322774 | controller | skipping: Conditional result was False
2025-12-01 00:24:44.335291 | 
2025-12-01 00:24:44.335430 | TASK [ensure-docker : Include tasks for docker install]
2025-12-01 00:24:44.357963 | controller | ok
2025-12-01 00:24:44.370464 | controller | included: /var/lib/zuul/builds/f12e4f52245a46c9aa9ee866168f732b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-12-01 00:24:44.382542 | 
2025-12-01 00:24:44.382671 | TASK [ensure-docker : Not Implemented]
2025-12-01 00:24:44.397252 | controller | skipping: Conditional result was False
2025-12-01 00:24:44.406455 | 
2025-12-01 00:24:44.406570 | TASK [Set up docker mirrors]
2025-12-01 00:24:44.429499 | controller | ok
2025-12-01 00:24:44.459613 | 
2025-12-01 00:24:44.459739 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-12-01 00:24:44.484495 | controller | skipping: Conditional result was False
2025-12-01 00:24:44.492974 | 
2025-12-01 00:24:44.493120 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-12-01 00:24:44.517493 | controller | skipping: Conditional result was False
2025-12-01 00:24:44.524099 | 
2025-12-01 00:24:44.524193 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-12-01 00:24:44.548237 | controller | skipping: Conditional result was False
2025-12-01 00:24:44.554563 | 
2025-12-01 00:24:44.554652 | TASK [use-docker-mirror : Set up docker mirrors]
2025-12-01 00:24:44.578602 | controller | skipping: Conditional result was False
2025-12-01 00:24:44.624756 | 
2025-12-01 00:24:44.624889 | TASK [Add all repositories]
2025-12-01 00:24:44.646593 | controller | ok
2025-12-01 00:24:44.673128 | 
2025-12-01 00:24:44.673284 | LOOP [ensure-package-repositories : Install repositories]
2025-12-01 00:24:44.705537 | controller | ok: "/var/lib/zuul/builds/f12e4f52245a46c9aa9ee866168f732b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-12-01 00:24:44.705705 | controller | ok: All items complete
2025-12-01 00:24:44.705731 | 
2025-12-01 00:24:44.724076 | controller | included: /var/lib/zuul/builds/f12e4f52245a46c9aa9ee866168f732b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-12-01 00:24:44.733338 | 
2025-12-01 00:24:44.733465 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-12-01 00:24:45.641294 | controller | changed
2025-12-01 00:24:45.655547 | 
2025-12-01 00:24:45.655720 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-12-01 00:24:48.716503 | controller | changed:
2025-12-01 00:24:48.716789 | controller | {
2025-12-01 00:24:48.716840 | 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-01 00:24:48.716887 | controller | }
2025-12-01 00:24:48.746421 | 
2025-12-01 00:24:48.746610 | LOOP [ensure-package-repositories : Import GPG keys]
2025-12-01 00:24:49.949358 | controller | changed:
2025-12-01 00:24:49.949577 | controller | {
2025-12-01 00:24:49.949614 | 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-01 00:24:49.949644 | controller | }
2025-12-01 00:24:49.949685 | controller | changed: All items complete
2025-12-01 00:24:49.949713 | 
2025-12-01 00:24:49.972632 | 
2025-12-01 00:24:49.972796 | TASK [ensure-package-repositories : Remove GPG temp directory]
2025-12-01 00:24:50.604664 | controller | changed
2025-12-01 00:24:50.611079 | 
2025-12-01 00:24:50.611149 | LOOP [ensure-package-repositories : Add repositories]
2025-12-01 00:24:51.354867 | controller | changed:
2025-12-01 00:24:51.355077 | controller | {
2025-12-01 00:24:51.355104 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2025-12-01 00:24:51.355124 | controller |   "description": "Docker CE Stable - $basearch",
2025-12-01 00:24:51.355140 | controller |   "gpgcheck": true,
2025-12-01 00:24:51.355156 | controller |   "name": "docker-ce-stable"
2025-12-01 00:24:51.355173 | controller | }
2025-12-01 00:24:51.382505 | 
2025-12-01 00:24:51.382637 | TASK [ensure-docker : Install pre-reqs]
2025-12-01 00:24:55.843115 | controller | ok: Nothing to do
2025-12-01 00:24:55.855930 | 
2025-12-01 00:24:55.856130 | TASK [ensure-docker : Remove packages]
2025-12-01 00:24:58.704989 | controller | ok: Nothing to do
2025-12-01 00:24:58.714044 | 
2025-12-01 00:24:58.714267 | TASK [ensure-docker : Install upstream docker using package]
2025-12-01 00:26:14.036555 | controller | changed
2025-12-01 00:26:14.049080 | 
2025-12-01 00:26:14.049347 | TASK [ensure-docker : Setup docker]
2025-12-01 00:26:14.087393 | controller | ok
2025-12-01 00:26:14.103669 | controller | included: /var/lib/zuul/builds/f12e4f52245a46c9aa9ee866168f732b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2025-12-01 00:26:14.123629 | 
2025-12-01 00:26:14.123761 | TASK [ensure-docker : Ensure "docker" group exists]
2025-12-01 00:26:15.078954 | controller | ok
2025-12-01 00:26:15.089691 | 
2025-12-01 00:26:15.089827 | TASK [ensure-docker : Add user to docker group]
2025-12-01 00:26:16.008123 | controller | changed
2025-12-01 00:26:16.014312 | 
2025-12-01 00:26:16.014390 | TASK [Update docker daemon proxy configuration]
2025-12-01 00:26:16.058727 | controller | skipping: Conditional result was False
2025-12-01 00:26:16.067200 | 
2025-12-01 00:26:16.067285 | TASK [Lower default MTU]
2025-12-01 00:26:16.092133 | controller | skipping: Conditional result was False
2025-12-01 00:26:16.118850 | 
2025-12-01 00:26:16.118990 | TASK [ensure-docker : Get the status of the docket.socket unit]
2025-12-01 00:26:16.939545 | controller | inactive
2025-12-01 00:26:17.491449 | controller | ok: Runtime: 0:00:00.014610
2025-12-01 00:26:17.497751 | 
2025-12-01 00:26:17.497837 | TASK [ensure-docker : Get the status of the docket unit]
2025-12-01 00:26:18.214466 | controller | inactive
2025-12-01 00:26:18.376334 | controller | ok: Runtime: 0:00:00.014474
2025-12-01 00:26:18.382746 | 
2025-12-01 00:26:18.382815 | TASK [ensure-docker : Restart docker]
2025-12-01 00:26:18.429735 | Trigger a Docker restart
2025-12-01 00:26:18.442654 | 
2025-12-01 00:26:18.442799 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2025-12-01 00:26:18.479554 | 
2025-12-01 00:26:18.479674 | TASK [ensure-docker : Install docker-compose]
2025-12-01 00:26:18.493426 | controller | skipping: Conditional result was False
2025-12-01 00:26:18.500103 | 
2025-12-01 00:26:18.500201 | TASK [ensure-docker : Flush handlers before role exit]
2025-12-01 00:26:18.513100 | 
2025-12-01 00:26:18.513209 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2025-12-01 00:26:21.658279 | controller | changed
2025-12-01 00:26:21.673222 | 
2025-12-01 00:26:21.673323 | TASK [ensure-docker : Assure docker service is running]
2025-12-01 00:26:25.009618 | controller | changed
2025-12-01 00:26:25.036797 | 
2025-12-01 00:26:25.036956 | TASK [ensure-docker : Assure docker.socket service is running]
2025-12-01 00:26:26.846851 | controller | ok
2025-12-01 00:26:26.858386 | 
2025-12-01 00:26:26.858489 | TASK [ensure-docker : Correct group ownership on docker sock]
2025-12-01 00:26:27.791145 | controller | ok
2025-12-01 00:26:27.806281 | 
2025-12-01 00:26:27.806448 | TASK [ensure-docker : Validate ability to talk with docker]
2025-12-01 00:26:28.403651 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2025-12-01 00:26:28.687248 | controller | ok: Runtime: 0:00:00.034158
2025-12-01 00:26:28.699943 | 
2025-12-01 00:26:28.700133 | TASK [ensure-docker : Get version details]
2025-12-01 00:26:29.376100 | controller | Client: Docker Engine - Community
2025-12-01 00:26:29.376234 | controller |  Version:           24.0.7
2025-12-01 00:26:29.376250 | controller |  API version:       1.43
2025-12-01 00:26:29.376259 | controller |  Go version:        go1.20.10
2025-12-01 00:26:29.376278 | controller |  Git commit:        afdd53b
2025-12-01 00:26:29.376286 | controller |  Built:             Thu Oct 26 09:09:39 2023
2025-12-01 00:26:29.376321 | controller |  OS/Arch:           linux/amd64
2025-12-01 00:26:29.376332 | controller |  Context:           default
2025-12-01 00:26:29.376342 | controller |
2025-12-01 00:26:29.376349 | controller | Server: Docker Engine - Community
2025-12-01 00:26:29.376355 | controller |  Engine:
2025-12-01 00:26:29.376382 | controller |   Version:          24.0.7
2025-12-01 00:26:29.378380 | controller |   API version:      1.43 (minimum version 1.12)
2025-12-01 00:26:29.378401 | controller |   Go version:       go1.20.10
2025-12-01 00:26:29.378408 | controller |   Git commit:       311b9ff
2025-12-01 00:26:29.378416 | controller |   Built:            Thu Oct 26 09:08:11 2023
2025-12-01 00:26:29.378422 | controller |   OS/Arch:          linux/amd64
2025-12-01 00:26:29.378429 | controller |   Experimental:     false
2025-12-01 00:26:29.378435 | controller |  containerd:
2025-12-01 00:26:29.378442 | controller |   Version:          1.6.25
2025-12-01 00:26:29.378448 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2025-12-01 00:26:29.378454 | controller |  runc:
2025-12-01 00:26:29.378459 | controller |   Version:          1.1.10
2025-12-01 00:26:29.378466 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2025-12-01 00:26:29.378476 | controller |  docker-init:
2025-12-01 00:26:29.378483 | controller |   Version:          0.19.0
2025-12-01 00:26:29.378489 | controller |   GitCommit:        de40ad0
2025-12-01 00:26:29.577119 | controller | ok: Runtime: 0:00:00.035530
2025-12-01 00:26:29.583476 | 
2025-12-01 00:26:29.583566 | TASK [ensure-docker : Dump installed docker details]
2025-12-01 00:26:29.623972 | {'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-01 00:26:29.343290', 'end': '2025-12-01 00:26:29.378820', 'delta': '0:00:00.035530', 'msg': '', 'zuul_log_id': 'fa163ec2-ffbe-9ed8-9470-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-01 00:26:29.630132 | 
2025-12-01 00:26:29.630227 | TASK [Use buildset registry]
2025-12-01 00:26:29.655376 | controller | skipping: Conditional result was False
2025-12-01 00:26:29.677587 | 
2025-12-01 00:26:29.677734 | TASK [Ensure python3.8 is present]
2025-12-01 00:26:29.692290 | controller | skipping: Conditional result was False
2025-12-01 00:26:29.699864 | 
2025-12-01 00:26:29.699991 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-12-01 00:26:29.721900 | controller | ok
2025-12-01 00:26:29.741883 | 
2025-12-01 00:26:29.742004 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-12-01 00:26:36.468629 | controller | ok: Nothing to do
2025-12-01 00:26:36.476234 | 
2025-12-01 00:26:36.476321 | TASK [our-ensure-python : Also install python3-devel]
2025-12-01 00:26:49.992627 | controller | changed
2025-12-01 00:26:50.022278 | 
2025-12-01 00:26:50.022503 | TASK [Run ensure-virtualenv role]
2025-12-01 00:26:50.073350 | controller | ok
2025-12-01 00:26:50.112433 | 
2025-12-01 00:26:50.112585 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-12-01 00:26:50.826715 | controller | /usr/bin/virtualenv
2025-12-01 00:26:50.969831 | controller | ok: Runtime: 0:00:00.005176
2025-12-01 00:26:50.986176 | 
2025-12-01 00:26:50.986453 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-12-01 00:26:51.016086 | controller | skipping: Conditional result was False
2025-12-01 00:26:51.017125 | controller | changed: All items complete
2025-12-01 00:26:51.017231 | 
2025-12-01 00:26:51.056535 | 
2025-12-01 00:26:51.056772 | TASK [Find the full path of the Python interpreter]
2025-12-01 00:26:51.629280 | controller | /usr/bin/python3.8
2025-12-01 00:26:51.931190 | controller | ok
2025-12-01 00:26:51.997559 | 
2025-12-01 00:26:51.997762 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-12-01 00:26:54.517481 | controller | created virtual environment CPython3.8.18.final.0-64 in 1133ms
2025-12-01 00:26:54.618696 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-12-01 00:26:54.618756 | 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-01 00:26:54.618775 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-12-01 00:26:54.618796 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-12-01 00:26:54.860094 | controller | changed
2025-12-01 00:26:54.868974 | 
2025-12-01 00:26:54.869101 | TASK [Set selinux package]
2025-12-01 00:26:54.897459 | controller | ok
2025-12-01 00:26:54.906179 | 
2025-12-01 00:26:54.906370 | TASK [Set selinux package (Fedora)]
2025-12-01 00:26:54.940293 | controller | ok
2025-12-01 00:26:54.948036 | 
2025-12-01 00:26:54.948136 | TASK [Install selinux into virtualenv]
2025-12-01 00:27:00.105250 | controller | Collecting selinux-please-lie-to-me
2025-12-01 00:27:00.235030 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-12-01 00:27:01.661454 | controller | Collecting setuptools<50.0.0
2025-12-01 00:27:01.675309 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-12-01 00:27:01.782718 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 8.2 MB/s eta 0:00:00
2025-12-01 00:27:02.009692 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-12-01 00:27:02.010048 | controller |   Attempting uninstall: setuptools
2025-12-01 00:27:02.011210 | controller |     Found existing installation: setuptools 62.6.0
2025-12-01 00:27:02.127325 | controller |     Uninstalling setuptools-62.6.0:
2025-12-01 00:27:02.141078 | controller |       Successfully uninstalled setuptools-62.6.0
2025-12-01 00:27:02.718904 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-12-01 00:27:02.892105 | controller |
2025-12-01 00:27:03.094345 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-12-01 00:27:03.094395 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-12-01 00:27:03.329580 | controller | ok: Runtime: 0:00:07.079598
2025-12-01 00:27:03.343781 | 
2025-12-01 00:27:03.344011 | TASK [Install pytest-forked into virtualenv]
2025-12-01 00:27:05.376455 | controller | Collecting pytest-forked
2025-12-01 00:27:05.428857 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-12-01 00:27:05.485354 | controller | Collecting py
2025-12-01 00:27:05.490185 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-12-01 00:27:05.516031 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 4.1 MB/s eta 0:00:00
2025-12-01 00:27:05.684116 | controller | Collecting pytest>=3.10
2025-12-01 00:27:05.689363 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2025-12-01 00:27:05.705869 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 26.4 MB/s eta 0:00:00
2025-12-01 00:27:05.785651 | controller | Collecting packaging
2025-12-01 00:27:05.790534 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-12-01 00:27:05.798518 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 12.0 MB/s eta 0:00:00
2025-12-01 00:27:05.837894 | controller | Collecting pluggy<2,>=1.5
2025-12-01 00:27:05.842808 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-12-01 00:27:05.871279 | controller | Collecting iniconfig
2025-12-01 00:27:05.876280 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-12-01 00:27:05.938354 | controller | Collecting tomli>=1
2025-12-01 00:27:05.943328 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2025-12-01 00:27:05.986840 | controller | Collecting exceptiongroup>=1.0.0rc8
2025-12-01 00:27:05.994221 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2025-12-01 00:27:06.065170 | controller | Collecting typing-extensions>=4.6.0
2025-12-01 00:27:06.070407 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2025-12-01 00:27:06.089572 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 2.3 MB/s eta 0:00:00
2025-12-01 00:27:06.174902 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-12-01 00:27:06.839563 | 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-01 00:27:06.844255 | controller |
2025-12-01 00:27:07.015809 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-12-01 00:27:07.015857 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-12-01 00:27:07.222640 | controller | ok: Runtime: 0:00:02.533634
2025-12-01 00:27:07.236727 | 
2025-12-01 00:27:07.236885 | TASK [Update pip]
2025-12-01 00:27:09.742353 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2025-12-01 00:27:10.081760 | controller | Collecting pip
2025-12-01 00:27:10.212219 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2025-12-01 00:27:10.350625 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 14.0 MB/s eta 0:00:00
2025-12-01 00:27:10.579626 | controller | Installing collected packages: pip
2025-12-01 00:27:10.580368 | controller |   Attempting uninstall: pip
2025-12-01 00:27:10.582247 | controller |     Found existing installation: pip 22.2.2
2025-12-01 00:27:11.303436 | controller |     Uninstalling pip-22.2.2:
2025-12-01 00:27:11.375597 | controller |       Successfully uninstalled pip-22.2.2
2025-12-01 00:27:14.018936 | controller | Successfully installed pip-25.0.1
2025-12-01 00:27:14.620926 | controller | ok: Runtime: 0:00:05.846926
2025-12-01 00:27:14.627981 | 
2025-12-01 00:27:14.628132 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-12-01 00:27:15.860834 | controller | changed
2025-12-01 00:27:15.870196 | 
2025-12-01 00:27:15.870383 | TASK [Install ansible into virtualenv]
2025-12-01 00:27:17.621005 | controller | Processing ./src/github.com/ansible/ansible
2025-12-01 00:27:17.622369 | controller |   Preparing metadata (setup.py): started
2025-12-01 00:27:18.305696 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-12-01 00:27:18.367295 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2025-12-01 00:27:18.385129 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-12-01 00:27:18.461653 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2025-12-01 00:27:18.467408 | 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-01 00:27:18.842421 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2025-12-01 00:27:18.847204 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-12-01 00:27:18.869630 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (25.0)
2025-12-01 00:27:19.067316 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2025-12-01 00:27:19.072433 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2025-12-01 00:27:19.079466 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.8/site-packages (from cryptography->ansible-base==2.10.17.post0) (4.13.2)
2025-12-01 00:27:19.174377 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2025-12-01 00:27:19.180200 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2025-12-01 00:27:19.208132 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2025-12-01 00:27:19.212907 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-12-01 00:27:19.229869 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2025-12-01 00:27:19.278137 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 108.3 MB/s eta 0:00:00
2025-12-01 00:27:19.282734 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-12-01 00:27:19.295820 | 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-01 00:27:19.310430 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 56.2 MB/s eta 0:00:00
2025-12-01 00:27:19.316648 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2025-12-01 00:27:19.330265 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2025-12-01 00:27:19.344247 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-12-01 00:27:19.366563 | controller | Building wheels for collected packages: ansible-base
2025-12-01 00:27:19.367802 | controller |   Building wheel for ansible-base (setup.py): started
2025-12-01 00:27:21.305739 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2025-12-01 00:27:21.319991 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=24c145f50a7ba44b9a8def982cafefcf8da0ce0d48675d458540eb89506dc777
2025-12-01 00:27:21.320058 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-5tkjtnow/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2025-12-01 00:27:21.320075 | controller | Successfully built ansible-base
2025-12-01 00:27:21.369389 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2025-12-01 00:27:23.828102 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-base-2.10.17.post0 cffi-1.17.1 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23
2025-12-01 00:27:24.239896 | controller | ok: Runtime: 0:00:07.137123
2025-12-01 00:27:24.254129 | 
2025-12-01 00:27:24.254307 | TASK [Setup download-artifact-fork role]
2025-12-01 00:27:24.280173 | controller | ok
2025-12-01 00:27:24.310893 | 
2025-12-01 00:27:24.311085 | TASK [download-artifact-fork : Parse inventory response]
2025-12-01 00:27:24.356378 | controller | ok
2025-12-01 00:27:24.413778 | 
2025-12-01 00:27:24.413911 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-12-01 00:27:24.438685 | controller | skipping: Conditional result was False
2025-12-01 00:27:24.445624 | 
2025-12-01 00:27:24.445701 | TASK [download-artifact-fork : Parse build response]
2025-12-01 00:27:24.470626 | controller | skipping: Conditional result was False
2025-12-01 00:27:24.477545 | 
2025-12-01 00:27:24.477623 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-12-01 00:27:25.253223 | controller | changed
2025-12-01 00:27:25.259490 | 
2025-12-01 00:27:25.259561 | LOOP [download-artifact-fork : Download archive by type]
2025-12-01 00:27:25.298298 | controller | skipping: Conditional result was False
2025-12-01 00:27:25.298574 | 
2025-12-01 00:27:27.043247 | controller | changed: OK (20215 bytes)
2025-12-01 00:27:27.076920 | 
2025-12-01 00:27:27.077131 | TASK [deploy-artifacts : define __collections]
2025-12-01 00:27:27.119382 | controller | ok
2025-12-01 00:27:27.132203 | 
2025-12-01 00:27:27.132335 | LOOP [deploy-artifacts : Create list of collections]
2025-12-01 00:27:27.164208 | controller | skipping: Conditional result was False
2025-12-01 00:27:27.177297 | controller | ok:
2025-12-01 00:27:27.177395 | controller | {
2025-12-01 00:27:27.177426 | controller |   "branch": "main",
2025-12-01 00:27:27.177451 | controller |   "job": "build-ansible-collection",
2025-12-01 00:27:27.177474 | controller |   "metadata": {
2025-12-01 00:27:27.177498 | controller |     "type": "ansible_collection",
2025-12-01 00:27:27.177520 | controller |     "version": "5.0.0"
2025-12-01 00:27:27.177542 | controller |   },
2025-12-01 00:27:27.177563 | controller |   "name": "ansible.network",
2025-12-01 00:27:27.177585 | controller |   "project": "ansible-collections/ansible.network",
2025-12-01 00:27:27.177606 | controller |   "url": "https://d56f16a8d163ea123620-d962b66fb9067adc1301998ce510d1d4.ssl.cf1.rackcdn.com/ansible/568d1533f87643c68ef1d8f550eff3c4/artifacts/ansible-network-5.0.0.tar.gz"
2025-12-01 00:27:27.177637 | controller | }
2025-12-01 00:27:27.188074 | 
2025-12-01 00:27:27.188189 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-12-01 00:27:28.362872 | controller | Starting galaxy collection install process
2025-12-01 00:27:28.363323 | controller | Process install dependency map
2025-12-01 00:27:43.212686 | controller | Starting collection install process
2025-12-01 00:27:43.212864 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2025-12-01 00:27:43.226011 | controller | ansible.network (5.0.0) was installed successfully
2025-12-01 00:27:43.226156 | controller | Installing 'ansible.netcommon:8.2.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2025-12-01 00:27:43.226341 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-8.2.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-42749mjbd6j3/tmp2ljdq15t
2025-12-01 00:27:44.806064 | controller | ansible.netcommon (8.2.0) was installed successfully
2025-12-01 00:27:44.806217 | controller | Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2025-12-01 00:27:44.806231 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-utils-6.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-42749mjbd6j3/tmp2ljdq15t
2025-12-01 00:27:46.484064 | controller | ansible.utils (6.0.0) was installed successfully
2025-12-01 00:27:46.484264 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2025-12-01 00:27:46.485217 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/arista-eos-12.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-42749mjbd6j3/tmp2ljdq15t
2025-12-01 00:27:48.858152 | controller | arista.eos (12.0.0) was installed successfully
2025-12-01 00:27:48.858278 | controller | Installing 'cisco.ios:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2025-12-01 00:27:48.858526 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-42749mjbd6j3/tmp2ljdq15t
2025-12-01 00:27:51.640276 | controller | cisco.ios (11.1.1) was installed successfully
2025-12-01 00:27:51.640442 | controller | Installing 'cisco.iosxr:12.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2025-12-01 00:27:51.640832 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.1.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-42749mjbd6j3/tmp2ljdq15t
2025-12-01 00:27:54.758278 | controller | cisco.iosxr (12.1.0) was installed successfully
2025-12-01 00:27:58.244467 | controller | Installing 'cisco.nxos:11.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2025-12-01 00:27:58.244526 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-42749mjbd6j3/tmp2ljdq15t
2025-12-01 00:27:58.244546 | controller | cisco.nxos (11.1.0) was installed successfully
2025-12-01 00:27:58.244719 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2025-12-01 00:27:58.244751 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/junipernetworks-junos-11.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-42749mjbd6j3/tmp2ljdq15t
2025-12-01 00:28:00.744125 | controller | junipernetworks.junos (11.0.0) was installed successfully
2025-12-01 00:28:01.078618 | controller | ok: Runtime: 0:00:33.054289
2025-12-01 00:28:01.096897 | 
2025-12-01 00:28:01.097116 | TASK [podman may need iptables kernel modules]
2025-12-01 00:28:02.463405 | controller | ok: Runtime: 0:00:00.005768
2025-12-01 00:28:02.514725 | 
2025-12-01 00:28:02.514887 | PLAY RECAP
2025-12-01 00:28:02.514951 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2025-12-01 00:28:02.514982 | 
2025-12-01 00:28:02.690333 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-12-01 00:28:02.691892 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-12-01 00:28:03.356477 | 
2025-12-01 00:28:03.356641 | PLAY [controller]
2025-12-01 00:28:03.383782 | 
2025-12-01 00:28:03.383968 | TASK [Copy the galaxy.yml on the controller]
2025-12-01 00:28:07.184181 | controller | changed
2025-12-01 00:28:07.192244 | 
2025-12-01 00:28:07.192339 | TASK [Load information from galaxy.yml]
2025-12-01 00:28:07.231967 | controller | ok
2025-12-01 00:28:07.242947 | 
2025-12-01 00:28:07.243108 | LOOP [Enable FIPS mode]
2025-12-01 00:28:07.270295 | 
2025-12-01 00:28:07.270511 | LOOP [ansible-test : Include branch specific variables.]
2025-12-01 00:28:07.318278 | controller | ok: "/var/lib/zuul/builds/f12e4f52245a46c9aa9ee866168f732b/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2025-12-01 00:28:07.318907 | 
2025-12-01 00:28:07.343495 | 
2025-12-01 00:28:07.343719 | TASK [ansible-test : Setup --skip-tags for test_options]
2025-12-01 00:28:07.405319 | controller | ok
2025-12-01 00:28:07.418069 | 
2025-12-01 00:28:07.418254 | TASK [ansible-test : Enable --inventory for network-integration]
2025-12-01 00:28:07.456549 | controller | skipping: Conditional result was False
2025-12-01 00:28:07.472633 | 
2025-12-01 00:28:07.472772 | TASK [ansible-test : Enable --retry-on-error]
2025-12-01 00:28:07.501074 | controller | skipping: Conditional result was False
2025-12-01 00:28:07.511848 | 
2025-12-01 00:28:07.511956 | TASK [ansible-test : Enable --continue-on-error]
2025-12-01 00:28:07.560995 | controller | ok
2025-12-01 00:28:07.574749 | 
2025-12-01 00:28:07.574886 | TASK [ansible-test : Enable --requirements]
2025-12-01 00:28:07.603495 | controller | skipping: Conditional result was False
2025-12-01 00:28:07.612751 | 
2025-12-01 00:28:07.612845 | TASK [ansible-test : Adjust options for unit tests]
2025-12-01 00:28:07.649352 | controller | skipping: Conditional result was False
2025-12-01 00:28:07.663095 | 
2025-12-01 00:28:07.663281 | TASK [ansible-test : Install requirements with unit tests]
2025-12-01 00:28:07.773665 | controller | ok
2025-12-01 00:28:07.780086 | 
2025-12-01 00:28:07.780188 | TASK [ansible-test : Set --skip-test]
2025-12-01 00:28:07.836961 | controller | skipping: Conditional result was False
2025-12-01 00:28:07.851697 | 
2025-12-01 00:28:07.851862 | TASK [ansible-test : Reduce verbosity]
2025-12-01 00:28:07.886694 | controller | ok
2025-12-01 00:28:07.892537 | 
2025-12-01 00:28:07.892606 | TASK [ansible-test : Setup --python option]
2025-12-01 00:28:07.916997 | controller | skipping: Conditional result was False
2025-12-01 00:28:07.923855 | 
2025-12-01 00:28:07.923923 | TASK [ansible-test : Setup --docker option]
2025-12-01 00:28:07.954060 | controller | ok
2025-12-01 00:28:07.961560 | 
2025-12-01 00:28:07.961622 | TASK [ansible-test : check if MANIFEST.json exists]
2025-12-01 00:28:09.080244 | controller | ok
2025-12-01 00:28:09.093477 | 
2025-12-01 00:28:09.093621 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2025-12-01 00:28:09.121562 | controller | skipping: Conditional result was False
2025-12-01 00:28:09.135386 | 
2025-12-01 00:28:09.135536 | TASK [ansible-test : Load information from MANIFEST.json]
2025-12-01 00:28:09.163870 | controller | skipping: Conditional result was False
2025-12-01 00:28:09.180814 | 
2025-12-01 00:28:09.180984 | TASK [ansible-test : Setup location of project for integration tests]
2025-12-01 00:28:09.208691 | controller | skipping: Conditional result was False
2025-12-01 00:28:09.216382 | 
2025-12-01 00:28:09.216536 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2025-12-01 00:28:10.652435 | controller | ok
2025-12-01 00:28:10.666317 | 
2025-12-01 00:28:10.666465 | TASK [ansible-test : Load information from galaxy.yml]
2025-12-01 00:28:10.711482 | controller | ok
2025-12-01 00:28:10.725446 | 
2025-12-01 00:28:10.725586 | TASK [ansible-test : Setup location of project for integration tests]
2025-12-01 00:28:10.758334 | controller | ok
2025-12-01 00:28:10.764278 | 
2025-12-01 00:28:10.764344 | TASK [ansible-test : Setup minimum test requirements]
2025-12-01 00:28:10.795275 | controller | ok
2025-12-01 00:28:10.800865 | 
2025-12-01 00:28:10.800927 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2025-12-01 00:28:11.669418 | controller | ok
2025-12-01 00:28:11.683457 | 
2025-12-01 00:28:11.683598 | TASK [ansible-test : Install python requirements]
2025-12-01 00:28:13.135532 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-12-01 00:28:13.152767 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2025-12-01 00:28:13.183809 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-12-01 00:28:13.188574 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2025-12-01 00:28:13.197153 | 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-01 00:28:13.204558 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2025-12-01 00:28:13.217294 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2025-12-01 00:28:13.267509 | controller | Installing collected packages: pathspec, yamllint
2025-12-01 00:28:13.342333 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2025-12-01 00:28:14.059082 | controller | ok: Runtime: 0:00:00.875592
2025-12-01 00:28:14.066615 | 
2025-12-01 00:28:14.066732 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2025-12-01 00:28:14.809991 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2025-12-01 00:28:15.422357 | controller | ERROR
2025-12-01 00:28:15.422805 | controller | {
2025-12-01 00:28:15.422874 | controller |   "delta": "0:00:00.006612",
2025-12-01 00:28:15.422920 | controller |   "end": "2025-12-01 00:28:14.810233",
2025-12-01 00:28:15.422961 | controller |   "msg": "non-zero return code",
2025-12-01 00:28:15.423064 | controller |   "rc": 1,
2025-12-01 00:28:15.423166 | controller |   "start": "2025-12-01 00:28:14.803621"
2025-12-01 00:28:15.423214 | controller | }
2025-12-01 00:28:15.423270 | controller | ERROR: Ignoring Errors
2025-12-01 00:28:15.439086 | 
2025-12-01 00:28:15.439252 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2025-12-01 00:28:15.817708 | controller | skipping: Conditional result was False
2025-12-01 00:28:15.834396 | 
2025-12-01 00:28:15.834574 | TASK [ansible-test : Enable persistent connection logging]
2025-12-01 00:28:17.282190 | controller | changed: section and option added
2025-12-01 00:28:17.294641 | 
2025-12-01 00:28:17.294780 | TASK [ansible-test : Set persistent ssh_type value]
2025-12-01 00:28:18.442783 | controller | changed: option added
2025-12-01 00:28:18.450567 | 
2025-12-01 00:28:18.450669 | TASK [ansible-test : Set ansible log path]
2025-12-01 00:28:19.712965 | controller | changed: section and option added
2025-12-01 00:28:19.726945 | 
2025-12-01 00:28:19.727163 | TASK [ansible-test : Identify the changed targets]
2025-12-01 00:28:20.098407 | controller | skipping: Conditional result was False
2025-12-01 00:28:20.113768 | 
2025-12-01 00:28:20.113977 | TASK [ansible-test : set_fact]
2025-12-01 00:28:20.232982 | controller | skipping: Conditional result was False
2025-12-01 00:28:20.242586 | 
2025-12-01 00:28:20.242724 | TASK [ansible-test : Set the targets]
2025-12-01 00:28:20.320734 | controller | ok
2025-12-01 00:28:20.326595 | 
2025-12-01 00:28:20.326736 | TASK [ansible-test : set_fact]
2025-12-01 00:28:20.340740 | controller | skipping: Conditional result was False
2025-12-01 00:28:20.348294 | 
2025-12-01 00:28:20.348458 | TASK [ansible-test : List the targets]
2025-12-01 00:28:20.708208 | controller | skipping: Conditional result was False
2025-12-01 00:28:20.717224 | 
2025-12-01 00:28:20.717372 | TASK [ansible-test : set_fact]
2025-12-01 00:28:20.763871 | controller | skipping: Conditional result was False
2025-12-01 00:28:20.770769 | 
2025-12-01 00:28:20.770859 | TASK [ansible-test : set_fact]
2025-12-01 00:28:20.796367 | controller | skipping: Conditional result was False
2025-12-01 00:28:20.804097 | 
2025-12-01 00:28:20.804201 | LOOP [ansible-test : set_fact]
2025-12-01 00:28:20.879875 | 
2025-12-01 00:28:20.880206 | TASK [ansible-test : set_fact]
2025-12-01 00:28:20.908329 | controller | skipping: Conditional result was False
2025-12-01 00:28:20.917962 | 
2025-12-01 00:28:20.918070 | TASK [ansible-test : debug]
2025-12-01 00:28:20.945425 | controller | skipping: Conditional result was False
2025-12-01 00:28:20.956636 | 
2025-12-01 00:28:20.956723 | TASK [ansible-test : debug]
2025-12-01 00:28:21.000978 | About to run: ansible-test sanity --requirements --docker -v
2025-12-01 00:28:21.009241 | 
2025-12-01 00:28:21.009360 | TASK [ansible-test : Run the test suite]
2025-12-01 00:28:22.849109 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2025-12-01 00:28:22.912104 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2025-12-01 00:28:24.777388 | controller | 2.9.0: Pulling from ansible/default-test-container
2025-12-01 00:28:25.152017 | controller | 171857c49d0f: Pulling fs layer
2025-12-01 00:28:25.152107 | controller | 419640447d26: Pulling fs layer
2025-12-01 00:28:25.152116 | controller | 61e52f862619: Pulling fs layer
2025-12-01 00:28:25.152123 | controller | 851de0426e26: Pulling fs layer
2025-12-01 00:28:25.152132 | controller | 37425716ea5b: Pulling fs layer
2025-12-01 00:28:25.152141 | controller | cd9b0c439616: Pulling fs layer
2025-12-01 00:28:25.152147 | controller | d4612dbd8855: Pulling fs layer
2025-12-01 00:28:25.152153 | controller | 554699364730: Pulling fs layer
2025-12-01 00:28:25.152159 | controller | 1ff15d4bdb3e: Pulling fs layer
2025-12-01 00:28:25.152168 | controller | 5f2e4724adea: Pulling fs layer
2025-12-01 00:28:25.152174 | controller | a02d9a95ccb8: Pulling fs layer
2025-12-01 00:28:25.152180 | controller | 989f4ce370cb: Pulling fs layer
2025-12-01 00:28:25.152186 | controller | 0d7f52cdf9df: Pulling fs layer
2025-12-01 00:28:25.152192 | controller | c423742351d5: Pulling fs layer
2025-12-01 00:28:25.152198 | controller | 109236341d21: Pulling fs layer
2025-12-01 00:28:25.152206 | controller | 07a2f61c96b8: Pulling fs layer
2025-12-01 00:28:25.152212 | controller | 041f122f27ae: Pulling fs layer
2025-12-01 00:28:25.152218 | controller | ca6f5098b12e: Pulling fs layer
2025-12-01 00:28:25.152223 | controller | 1eb3e7b2afd9: Pulling fs layer
2025-12-01 00:28:25.152229 | controller | 18a0b750727a: Pulling fs layer
2025-12-01 00:28:25.152235 | controller | 17f2f596716c: Pulling fs layer
2025-12-01 00:28:25.152242 | controller | f35a9998a9bd: Pulling fs layer
2025-12-01 00:28:25.152248 | controller | fdfc10566405: Pulling fs layer
2025-12-01 00:28:25.152254 | controller | 1ff1c442c5fe: Pulling fs layer
2025-12-01 00:28:25.152259 | controller | a3443c4a17ed: Pulling fs layer
2025-12-01 00:28:25.152265 | controller | 0edcf9fb2b15: Pulling fs layer
2025-12-01 00:28:25.152271 | controller | 5af43b69e845: Pulling fs layer
2025-12-01 00:28:25.152277 | controller | 1651773effb2: Pulling fs layer
2025-12-01 00:28:25.152282 | controller | 79e23c5c0fe4: Pulling fs layer
2025-12-01 00:28:25.152293 | controller | e5058b1faaa6: Pulling fs layer
2025-12-01 00:28:25.152300 | controller | 846775490569: Pulling fs layer
2025-12-01 00:28:25.152306 | controller | 851de0426e26: Waiting
2025-12-01 00:28:25.152313 | controller | 37425716ea5b: Waiting
2025-12-01 00:28:25.152319 | controller | cd9b0c439616: Waiting
2025-12-01 00:28:25.152325 | controller | d4612dbd8855: Waiting
2025-12-01 00:28:25.152331 | controller | 554699364730: Waiting
2025-12-01 00:28:25.152341 | controller | 1ff15d4bdb3e: Waiting
2025-12-01 00:28:25.152357 | controller | 5f2e4724adea: Waiting
2025-12-01 00:28:25.152366 | controller | a02d9a95ccb8: Waiting
2025-12-01 00:28:25.152372 | controller | 989f4ce370cb: Waiting
2025-12-01 00:28:25.152378 | controller | 0d7f52cdf9df: Waiting
2025-12-01 00:28:25.152384 | controller | c423742351d5: Waiting
2025-12-01 00:28:25.152389 | controller | 109236341d21: Waiting
2025-12-01 00:28:25.152395 | controller | 07a2f61c96b8: Waiting
2025-12-01 00:28:25.152401 | controller | 041f122f27ae: Waiting
2025-12-01 00:28:25.152407 | controller | ca6f5098b12e: Waiting
2025-12-01 00:28:25.152413 | controller | 18a0b750727a: Waiting
2025-12-01 00:28:25.152430 | controller | 17f2f596716c: Waiting
2025-12-01 00:28:25.152436 | controller | 1651773effb2: Waiting
2025-12-01 00:28:25.152445 | controller | 79e23c5c0fe4: Waiting
2025-12-01 00:28:25.152451 | controller | f35a9998a9bd: Waiting
2025-12-01 00:28:25.152457 | controller | e5058b1faaa6: Waiting
2025-12-01 00:28:25.152463 | controller | fdfc10566405: Waiting
2025-12-01 00:28:25.152469 | controller | 1ff1c442c5fe: Waiting
2025-12-01 00:28:25.152475 | controller | a3443c4a17ed: Waiting
2025-12-01 00:28:25.152481 | controller | 0edcf9fb2b15: Waiting
2025-12-01 00:28:25.152487 | controller | 5af43b69e845: Waiting
2025-12-01 00:28:25.152493 | controller | 1eb3e7b2afd9: Waiting
2025-12-01 00:28:25.152509 | controller | 61e52f862619: Verifying Checksum
2025-12-01 00:28:25.156408 | controller | 61e52f862619: Download complete
2025-12-01 00:28:25.156439 | controller | 419640447d26: Verifying Checksum
2025-12-01 00:28:25.565212 | controller | 419640447d26: Download complete
2025-12-01 00:28:25.565270 | controller | 171857c49d0f: Verifying Checksum
2025-12-01 00:28:25.988503 | controller | 171857c49d0f: Download complete
2025-12-01 00:28:25.988562 | controller | 851de0426e26: Download complete
2025-12-01 00:28:26.642726 | controller | cd9b0c439616: Verifying Checksum
2025-12-01 00:28:26.780213 | controller | cd9b0c439616: Download complete
2025-12-01 00:28:26.780267 | controller | d4612dbd8855: Verifying Checksum
2025-12-01 00:28:26.780760 | controller | d4612dbd8855: Download complete
2025-12-01 00:28:27.631552 | controller | 1ff15d4bdb3e: Verifying Checksum
2025-12-01 00:28:28.481527 | controller | 1ff15d4bdb3e: Download complete
2025-12-01 00:28:28.481589 | controller | 5f2e4724adea: Verifying Checksum
2025-12-01 00:28:28.576965 | controller | 5f2e4724adea: Download complete
2025-12-01 00:28:28.577062 | controller | 171857c49d0f: Pull complete
2025-12-01 00:28:28.601620 | controller | 419640447d26: Pull complete
2025-12-01 00:28:28.619159 | controller | 61e52f862619: Pull complete
2025-12-01 00:28:28.639400 | controller | 851de0426e26: Pull complete
2025-12-01 00:28:29.779358 | controller | a02d9a95ccb8: Download complete
2025-12-01 00:28:30.499015 | controller | 989f4ce370cb: Verifying Checksum
2025-12-01 00:28:31.248003 | controller | 989f4ce370cb: Download complete
2025-12-01 00:28:31.248131 | controller | 0d7f52cdf9df: Verifying Checksum
2025-12-01 00:28:31.248242 | controller | 0d7f52cdf9df: Download complete
2025-12-01 00:28:31.965244 | controller | c423742351d5: Verifying Checksum
2025-12-01 00:28:31.965360 | controller | c423742351d5: Download complete
2025-12-01 00:28:36.405931 | controller | 554699364730: Verifying Checksum
2025-12-01 00:28:36.406088 | controller | 554699364730: Download complete
2025-12-01 00:28:37.171471 | controller | 07a2f61c96b8: Verifying Checksum
2025-12-01 00:28:37.171612 | controller | 07a2f61c96b8: Download complete
2025-12-01 00:28:38.044490 | controller | 041f122f27ae: Verifying Checksum
2025-12-01 00:28:38.044661 | controller | 041f122f27ae: Download complete
2025-12-01 00:28:39.694792 | controller | ca6f5098b12e: Verifying Checksum
2025-12-01 00:28:39.694945 | controller | ca6f5098b12e: Download complete
2025-12-01 00:28:40.984374 | controller | 1eb3e7b2afd9: Verifying Checksum
2025-12-01 00:28:40.984450 | controller | 1eb3e7b2afd9: Download complete
2025-12-01 00:28:41.060319 | controller | 109236341d21: Download complete
2025-12-01 00:28:41.766468 | controller | 17f2f596716c: Verifying Checksum
2025-12-01 00:28:41.766662 | controller | 17f2f596716c: Download complete
2025-12-01 00:28:41.802637 | controller | 18a0b750727a: Verifying Checksum
2025-12-01 00:28:42.544459 | controller | 18a0b750727a: Download complete
2025-12-01 00:28:42.544530 | controller | f35a9998a9bd: Verifying Checksum
2025-12-01 00:28:42.561065 | controller | f35a9998a9bd: Download complete
2025-12-01 00:28:42.561126 | controller | fdfc10566405: Verifying Checksum
2025-12-01 00:28:43.316913 | controller | fdfc10566405: Download complete
2025-12-01 00:28:43.316983 | controller | 1ff1c442c5fe: Verifying Checksum
2025-12-01 00:28:49.794638 | controller | 1ff1c442c5fe: Download complete
2025-12-01 00:28:49.794718 | controller | a3443c4a17ed: Verifying Checksum
2025-12-01 00:28:52.144655 | controller | a3443c4a17ed: Download complete
2025-12-01 00:28:52.144716 | controller | 0edcf9fb2b15: Verifying Checksum
2025-12-01 00:28:53.328985 | controller | 0edcf9fb2b15: Download complete
2025-12-01 00:28:53.329096 | controller | 5af43b69e845: Verifying Checksum
2025-12-01 00:28:53.329219 | controller | 5af43b69e845: Download complete
2025-12-01 00:28:57.342282 | controller | 79e23c5c0fe4: Verifying Checksum
2025-12-01 00:28:57.342331 | controller | 79e23c5c0fe4: Download complete
2025-12-01 00:28:57.944996 | controller | 1651773effb2: Verifying Checksum
2025-12-01 00:28:57.945077 | controller | 1651773effb2: Download complete
2025-12-01 00:28:59.418284 | controller | 37425716ea5b: Verifying Checksum
2025-12-01 00:28:59.418336 | controller | 37425716ea5b: Download complete
2025-12-01 00:29:01.007206 | controller | e5058b1faaa6: Verifying Checksum
2025-12-01 00:29:01.007290 | controller | e5058b1faaa6: Download complete
2025-12-01 00:29:05.288505 | controller | 846775490569: Verifying Checksum
2025-12-01 00:29:05.288586 | controller | 846775490569: Download complete
2025-12-01 00:29:14.974989 | controller | 37425716ea5b: Pull complete
2025-12-01 00:29:14.993788 | controller | cd9b0c439616: Pull complete
2025-12-01 00:29:15.012728 | controller | d4612dbd8855: Pull complete
2025-12-01 00:29:19.200946 | controller | 554699364730: Pull complete
2025-12-01 00:29:19.227679 | controller | 1ff15d4bdb3e: Pull complete
2025-12-01 00:29:19.253899 | controller | 5f2e4724adea: Pull complete
2025-12-01 00:29:19.309436 | controller | a02d9a95ccb8: Pull complete
2025-12-01 00:29:19.330830 | controller | 989f4ce370cb: Pull complete
2025-12-01 00:29:19.350289 | controller | 0d7f52cdf9df: Pull complete
2025-12-01 00:29:19.377905 | controller | c423742351d5: Pull complete
2025-12-01 00:29:21.257463 | controller | 109236341d21: Pull complete
2025-12-01 00:29:21.282826 | controller | 07a2f61c96b8: Pull complete
2025-12-01 00:29:21.301408 | controller | 041f122f27ae: Pull complete
2025-12-01 00:29:21.480102 | controller | ca6f5098b12e: Pull complete
2025-12-01 00:29:21.529051 | controller | 1eb3e7b2afd9: Pull complete
2025-12-01 00:29:21.578859 | controller | 18a0b750727a: Pull complete
2025-12-01 00:29:21.602092 | controller | 17f2f596716c: Pull complete
2025-12-01 00:29:21.627822 | controller | f35a9998a9bd: Pull complete
2025-12-01 00:29:21.654364 | controller | fdfc10566405: Pull complete
2025-12-01 00:29:21.678942 | controller | 1ff1c442c5fe: Pull complete
2025-12-01 00:29:23.480551 | controller | a3443c4a17ed: Pull complete
2025-12-01 00:29:25.148633 | controller | 0edcf9fb2b15: Pull complete
2025-12-01 00:29:27.607783 | controller | 5af43b69e845: Pull complete
2025-12-01 00:29:31.577482 | controller | 1651773effb2: Pull complete
2025-12-01 00:29:34.355746 | controller | 79e23c5c0fe4: Pull complete
2025-12-01 00:29:36.374986 | controller | e5058b1faaa6: Pull complete
2025-12-01 00:29:39.161381 | controller | 846775490569: Pull complete
2025-12-01 00:29:39.193477 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2025-12-01 00:29:39.196874 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2025-12-01 00:29:39.205591 | controller | quay.io/ansible/default-test-container:2.9.0
2025-12-01 00:29:39.211498 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2025-12-01 00:29:39.249121 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2025-12-01 00:29:40.906883 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2025-12-01 00:29:40.906931 | controller | 04a5f4cda3ee: Pulling fs layer
2025-12-01 00:29:40.906941 | controller | ff496a88c8ed: Pulling fs layer
2025-12-01 00:29:40.906992 | controller | 0ce83f459fe7: Pulling fs layer
2025-12-01 00:29:40.907052 | controller | 2e5170e1f099: Pulling fs layer
2025-12-01 00:29:40.907102 | controller | 7641eb41b08c: Pulling fs layer
2025-12-01 00:29:40.907150 | controller | ad15fa9da398: Pulling fs layer
2025-12-01 00:29:40.907190 | controller | 087d91352424: Pulling fs layer
2025-12-01 00:29:40.907222 | controller | 8b92efd6a100: Pulling fs layer
2025-12-01 00:29:40.907387 | controller | 7641eb41b08c: Waiting
2025-12-01 00:29:40.907469 | controller | ad15fa9da398: Waiting
2025-12-01 00:29:40.907483 | controller | 087d91352424: Waiting
2025-12-01 00:29:40.907564 | controller | 8b92efd6a100: Waiting
2025-12-01 00:29:40.907913 | controller | 2e5170e1f099: Waiting
2025-12-01 00:29:41.708780 | controller | 0ce83f459fe7: Verifying Checksum
2025-12-01 00:29:41.784427 | controller | 0ce83f459fe7: Download complete
2025-12-01 00:29:41.784501 | controller | ff496a88c8ed: Verifying Checksum
2025-12-01 00:29:41.896795 | controller | ff496a88c8ed: Download complete
2025-12-01 00:29:41.896854 | controller | 04a5f4cda3ee: Verifying Checksum
2025-12-01 00:29:42.622337 | controller | 04a5f4cda3ee: Download complete
2025-12-01 00:29:42.622395 | controller | 7641eb41b08c: Verifying Checksum
2025-12-01 00:29:42.755285 | controller | 7641eb41b08c: Download complete
2025-12-01 00:29:42.755345 | controller | ad15fa9da398: Verifying Checksum
2025-12-01 00:29:43.488115 | controller | ad15fa9da398: Download complete
2025-12-01 00:29:43.488175 | controller | 8b92efd6a100: Verifying Checksum
2025-12-01 00:29:43.548803 | controller | 8b92efd6a100: Download complete
2025-12-01 00:29:43.548864 | controller | 087d91352424: Verifying Checksum
2025-12-01 00:29:43.839260 | controller | 087d91352424: Download complete
2025-12-01 00:29:43.839316 | controller | 04a5f4cda3ee: Pull complete
2025-12-01 00:29:43.873986 | controller | ff496a88c8ed: Pull complete
2025-12-01 00:29:43.900273 | controller | 0ce83f459fe7: Pull complete
2025-12-01 00:29:46.210759 | controller | 2e5170e1f099: Verifying Checksum
2025-12-01 00:29:47.414201 | controller | 2e5170e1f099: Download complete
2025-12-01 00:29:47.414258 | controller | 2e5170e1f099: Pull complete
2025-12-01 00:29:47.435178 | controller | 7641eb41b08c: Pull complete
2025-12-01 00:29:47.458846 | controller | ad15fa9da398: Pull complete
2025-12-01 00:29:48.332991 | controller | 087d91352424: Pull complete
2025-12-01 00:29:48.355772 | controller | 8b92efd6a100: Pull complete
2025-12-01 00:29:48.370839 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2025-12-01 00:29:48.374044 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2025-12-01 00:29:48.380402 | controller | quay.io/ansible/pypi-test-container:1.0.0
2025-12-01 00:29:48.384270 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2025-12-01 00:29:56.275369 | controller | Run command: docker inspect d799c5c816af65c3127acac42cdfc540c9c77e5887494d3cd76c509e186be7ac
2025-12-01 00:29:56.336734 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2025-12-01 00:29:56.350258 | controller | Including collection: ansible.netcommon (292 files)
2025-12-01 00:29:56.350332 | controller | Including collection: ansible.network (23 files)
2025-12-01 00:29:56.364803 | controller | Including collection: ansible.utils (442 files)
2025-12-01 00:29:56.402952 | controller | Including collection: arista.eos (1022 files)
2025-12-01 00:29:56.445737 | controller | Including collection: cisco.ios (1172 files)
2025-12-01 00:29:56.485969 | controller | Including collection: cisco.iosxr (1043 files)
2025-12-01 00:29:56.556733 | controller | Including collection: cisco.nxos (1684 files)
2025-12-01 00:29:56.598403 | controller | Including collection: junipernetworks.junos (963 files)
2025-12-01 00:29:56.637692 | controller | Creating a payload archive containing 7367 files...
2025-12-01 00:29:58.633662 | controller | Created a 5982212 byte payload archive containing 7367 files in 1 seconds.
2025-12-01 00:29:58.637495 | controller | Assuming Docker is available on localhost.
2025-12-01 00:29:58.637852 | 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:2.9.0
2025-12-01 00:29:59.156156 | controller | Run command: docker exec -i 40fdd5925be5623b5a2cac7a78e21acaa5e0264441d3a9446c297a19d1e1d0e2 dd of=/root/docker.sh bs=65536
2025-12-01 00:29:59.246564 | controller | Run command: docker exec 40fdd5925be5623b5a2cac7a78e21acaa5e0264441d3a9446c297a19d1e1d0e2 /bin/bash /root/docker.sh
2025-12-01 00:29:59.333868 | controller | Run command: docker exec -i 40fdd5925be5623b5a2cac7a78e21acaa5e0264441d3a9446c297a19d1e1d0e2 dd of=/root/test.tgz bs=65536
2025-12-01 00:29:59.462142 | controller | Run command: docker exec 40fdd5925be5623b5a2cac7a78e21acaa5e0264441d3a9446c297a19d1e1d0e2 tar oxzf /root/test.tgz -C /root
2025-12-01 00:30:00.462486 | controller | Run command: docker exec 40fdd5925be5623b5a2cac7a78e21acaa5e0264441d3a9446c297a19d1e1d0e2 /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata tests/output/.tmp/metadata-e16l04to.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2025-12-01 00:30:00.893598 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2025-12-01 00:30:00.894091 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2025-12-01 00:30:00.894442 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2025-12-01 00:30:00.896663 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2025-12-01 00:30:00.897597 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2025-12-01 00:30:00.904834 | controller | No tests applicable.
2025-12-01 00:30:00.904938 | controller | Running sanity test 'ansible-doc' with Python 3.6
2025-12-01 00:30:00.905267 | controller | No tests applicable.
2025-12-01 00:30:00.905349 | controller | Running sanity test 'changelog' with Python 3.6
2025-12-01 00:30:00.907719 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/ansible-test.txt
2025-12-01 00:30:01.849911 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt
2025-12-01 00:30:02.416619 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2025-12-01 00:30:17.438174 | 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/antsibull-changelog/
2025-12-01 00:30:32.955038 | 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/antsibull-changelog/
2025-12-01 00:30:48.971654 | 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/antsibull-changelog/
2025-12-01 00:31:05.991173 | 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/antsibull-changelog/
2025-12-01 00:31:25.001533 | 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/antsibull-changelog/
2025-12-01 00:31:40.014381 | controller |   ERROR: Could not find a version that satisfies the requirement antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2)) (from versions: none)
2025-12-01 00:31:40.015256 | controller | ERROR: No matching distribution found for antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2025-12-01 00:31:40.110488 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2025-12-01 00:31:40.110566 | controller | ERROR: Command "/usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt" returned exit status 1.
2025-12-01 00:31:40.110583 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2025-12-01 00:31:40.149425 | controller | Removing custom PyPI hosts entries: /etc/hosts
2025-12-01 00:31:40.149507 | controller | Run command: docker exec 40fdd5925be5623b5a2cac7a78e21acaa5e0264441d3a9446c297a19d1e1d0e2 tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2025-12-01 00:31:40.223575 | controller | Run command: docker exec -i 40fdd5925be5623b5a2cac7a78e21acaa5e0264441d3a9446c297a19d1e1d0e2 dd if=/root/output.tgz bs=65536
2025-12-01 00:31:40.290127 | controller | Run command: tar oxzf /tmp/ansible-result-3hd3j8y7.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2025-12-01 00:31:40.303623 | controller | Run command: docker rm -f d799c5c816af65c3127acac42cdfc540c9c77e5887494d3cd76c509e186be7ac
2025-12-01 00:31:40.511544 | controller | Run command: docker rm -f 40fdd5925be5623b5a2cac7a78e21acaa5e0264441d3a9446c297a19d1e1d0e2
2025-12-01 00:31:41.130299 | controller | ERROR: Command "docker exec 40fdd5925be5623b5a2cac7a78e21acaa5e0264441d3a9446c297a19d1e1d0e2 /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata tests/output/.tmp/metadata-e16l04to.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2025-12-01 00:31:41.482694 | controller | ERROR
2025-12-01 00:31:41.482982 | controller | {
2025-12-01 00:31:41.483018 | controller |   "delta": "0:03:18.786411",
2025-12-01 00:31:41.483088 | controller |   "end": "2025-12-01 00:31:41.179146",
2025-12-01 00:31:41.483112 | controller |   "msg": "non-zero return code",
2025-12-01 00:31:41.483131 | controller |   "rc": 1,
2025-12-01 00:31:41.483152 | controller |   "start": "2025-12-01 00:28:22.392735"
2025-12-01 00:31:41.483171 | controller | }
failure
2025-12-01 00:31:41.487792 | 
2025-12-01 00:31:41.487851 | PLAY RECAP
2025-12-01 00:31:41.487904 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2025-12-01 00:31:41.487938 | 
2025-12-01 00:31:41.622022 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-12-01 00:31:41.623042 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-01 00:31:42.218651 | 
2025-12-01 00:31:42.218783 | PLAY [all]
2025-12-01 00:31:42.241325 | 
2025-12-01 00:31:42.241475 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-12-01 00:31:45.227644 | controller | changed: non-zero return code
2025-12-01 00:31:45.241484 | 
2025-12-01 00:31:45.241649 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-12-01 00:31:45.272669 | controller | skipping: Conditional result was False
2025-12-01 00:31:45.288489 | 
2025-12-01 00:31:45.288658 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-12-01 00:31:45.338675 | 
2025-12-01 00:31:45.339135 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-12-01 00:31:45.379669 | 
2025-12-01 00:31:45.379921 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-12-01 00:31:45.407508 | controller | skipping: Conditional result was False
2025-12-01 00:31:45.417060 | 
2025-12-01 00:31:45.417226 | LOOP [fetch-subunit-output : Generate subunit file]
2025-12-01 00:31:45.454900 | 
2025-12-01 00:31:45.455149 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-12-01 00:31:45.481675 | controller | skipping: Conditional result was False
2025-12-01 00:31:45.493113 | 
2025-12-01 00:31:45.493206 | TASK [fetch-subunit-output : Remove the temporary file]
2025-12-01 00:31:45.519498 | controller | skipping: Conditional result was False
2025-12-01 00:31:45.529855 | 
2025-12-01 00:31:45.529954 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-12-01 00:31:45.557356 | controller | skipping: Conditional result was False
2025-12-01 00:31:45.598862 | 
2025-12-01 00:31:45.598987 | PLAY RECAP
2025-12-01 00:31:45.599072 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-01 00:31:45.599107 | 
2025-12-01 00:31:45.738059 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-01 00:31:45.739000 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-12-01 00:31:46.393381 | 
2025-12-01 00:31:46.393658 | PLAY [all:!appliance*]
2025-12-01 00:31:46.435491 | 
2025-12-01 00:31:46.435773 | TASK [unregister the node]
2025-12-01 00:31:46.806065 | controller | skipping: Conditional result was False
2025-12-01 00:31:46.815451 | 
2025-12-01 00:31:46.815651 | TASK [include_role : fetch-output]
2025-12-01 00:31:46.861106 | controller | ok
2025-12-01 00:31:46.891094 | 
2025-12-01 00:31:46.891297 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-01 00:31:46.938813 | controller | skipping: Conditional result was False
2025-12-01 00:31:46.946161 | 
2025-12-01 00:31:46.946308 | TASK [fetch-output : Set log path for single node]
2025-12-01 00:31:46.989482 | controller | ok
2025-12-01 00:31:46.996293 | 
2025-12-01 00:31:46.996524 | LOOP [fetch-output : Ensure local output dirs]
2025-12-01 00:31:47.399698 | controller -> localhost | ok: "/var/lib/zuul/builds/f12e4f52245a46c9aa9ee866168f732b/work/logs"
2025-12-01 00:31:47.657522 | controller -> localhost | changed: "/var/lib/zuul/builds/f12e4f52245a46c9aa9ee866168f732b/work/artifacts"
2025-12-01 00:31:47.931709 | controller -> localhost | changed: "/var/lib/zuul/builds/f12e4f52245a46c9aa9ee866168f732b/work/docs"
2025-12-01 00:31:47.951932 | 
2025-12-01 00:31:47.952141 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-01 00:31:50.265628 | controller | changed:
2025-12-01 00:31:50.266249 | controller | .d..t...... ./
2025-12-01 00:31:50.266340 | controller | cd+++++++++ controller/
2025-12-01 00:31:50.266417 | controller | changed: All items complete
2025-12-01 00:31:50.266484 | 
2025-12-01 00:31:52.361543 | controller | changed: .d..t...... ./
2025-12-01 00:31:54.387611 | controller | changed: .d..t...... ./
2025-12-01 00:31:54.421592 | 
2025-12-01 00:31:54.421826 | TASK [include_role : fetch-output-openshift]
2025-12-01 00:31:54.438251 | controller | skipping: Conditional result was False
2025-12-01 00:31:54.449397 | 
2025-12-01 00:31:54.449642 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-01 00:31:54.487533 | controller | skipping: Conditional result was False
2025-12-01 00:31:54.495445 | controller | skipping: Conditional result was False
2025-12-01 00:31:54.539498 | 
2025-12-01 00:31:54.539655 | PLAY [localhost]
2025-12-01 00:31:54.558007 | 
2025-12-01 00:31:54.558215 | TASK [Run Zuul manifest role]
2025-12-01 00:31:54.580787 | localhost | ok
2025-12-01 00:31:54.595246 | 
2025-12-01 00:31:54.595418 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-01 00:31:54.986216 | localhost | changed
2025-12-01 00:31:54.992084 | 
2025-12-01 00:31:54.992252 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-01 00:31:55.028934 | localhost | ok
2025-12-01 00:31:55.044634 | 
2025-12-01 00:31:55.044818 | TASK [Set zuul-log-path fact]
2025-12-01 00:31:55.067517 | localhost | ok
2025-12-01 00:31:55.116538 | 
2025-12-01 00:31:55.116688 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-01 00:31:55.146616 | localhost | ok
2025-12-01 00:31:55.154721 | 
2025-12-01 00:31:55.154784 | LOOP [Run upload-logs-swift role]
2025-12-01 00:31:55.191124 | localhost | Output suppressed because no_log was given
2025-12-01 00:31:55.218125 | 
2025-12-01 00:31:55.218297 | TASK [Set zuul-log-path fact]
2025-12-01 00:31:55.244940 | localhost | skipping: Conditional result was False
2025-12-01 00:31:55.252326 | 
2025-12-01 00:31:55.252496 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-12-01 00:31:55.645259 | localhost -> localhost | ok: Runtime: 0:00:00.005740
2025-12-01 00:31:55.655743 | 
2025-12-01 00:31:55.655848 | TASK [upload-logs-swift : Upload logs to swift]