2025-12-25 00:06:54.244877 | Job console starting...
2025-12-25 00:06:54.252762 | Updating repositories
2025-12-25 00:06:54.397941 | Preparing job workspace
2025-12-25 00:06:58.552917 | Running Ansible setup...
2025-12-25 00:07:03.546822 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-12-25 00:07:04.288476 | 
2025-12-25 00:07:04.288633 | PLAY [localhost]
2025-12-25 00:07:04.299104 | 
2025-12-25 00:07:04.299240 | TASK [Gathering Facts]
2025-12-25 00:07:05.333842 | localhost | ok
2025-12-25 00:07:05.356765 | 
2025-12-25 00:07:05.356924 | TASK [Setup log path fact]
2025-12-25 00:07:05.379243 | localhost | ok
2025-12-25 00:07:05.400201 | 
2025-12-25 00:07:05.400370 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-25 00:07:05.431948 | localhost | ok
2025-12-25 00:07:05.445486 | 
2025-12-25 00:07:05.445627 | TASK [emit-job-header : Print job information]
2025-12-25 00:07:05.487446 | # Job Information
2025-12-25 00:07:05.487697 | Ansible Version: 2.15.12
2025-12-25 00:07:05.487745 | Job: ansible-test-sanity-docker-stable-2.10
2025-12-25 00:07:05.487776 | Pipeline: periodic
2025-12-25 00:07:05.487803 | Executor: ze01.softwarefactory-project.io
2025-12-25 00:07:05.487830 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-12-25 00:07:05.487861 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/5ea/ansible/5eab4088b67d47e9bfbd747b1bee96b0/
2025-12-25 00:07:05.487889 | Event ID: 9bcd164eed0a4284892079c0f800abca
2025-12-25 00:07:05.493241 | 
2025-12-25 00:07:05.493341 | LOOP [emit-job-header : Print node information]
2025-12-25 00:07:05.633937 | localhost | ok:
2025-12-25 00:07:05.634221 | localhost | # Node Information
2025-12-25 00:07:05.634261 | localhost | Inventory Hostname: controller
2025-12-25 00:07:05.634291 | localhost | Hostname: np0005570306
2025-12-25 00:07:05.634319 | localhost | Username: zuul
2025-12-25 00:07:05.634355 | localhost | Distro: Fedora 37
2025-12-25 00:07:05.634384 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2025-12-25 00:07:05.634410 | localhost | Region: ca-ymq-1
2025-12-25 00:07:05.634436 | localhost | Label: ansible-fedora-37-1vcpu
2025-12-25 00:07:05.634461 | localhost | Product Name: OpenStack Nova
2025-12-25 00:07:05.634486 | localhost | Interface IP: 162.253.55.12
2025-12-25 00:07:05.653901 | 
2025-12-25 00:07:05.654064 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-25 00:07:06.066541 | localhost -> localhost | changed
2025-12-25 00:07:06.075937 | 
2025-12-25 00:07:06.076077 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-25 00:07:07.142725 | localhost -> localhost | changed
2025-12-25 00:07:07.167371 | 
2025-12-25 00:07:07.167475 | PLAY [all:!appliance*]
2025-12-25 00:07:07.191127 | 
2025-12-25 00:07:07.191308 | TASK [include_role : start-zuul-console]
2025-12-25 00:07:07.224264 | controller | ok
2025-12-25 00:07:07.240077 | 
2025-12-25 00:07:07.240216 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-25 00:07:07.654516 | controller | ok
2025-12-25 00:07:07.676467 | 
2025-12-25 00:07:07.676630 | TASK [use-our-mirror : Retrieve the IP address]
2025-12-25 00:07:29.998404 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-12-25 00:07:30.018694 | 
2025-12-25 00:07:30.018847 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-12-25 00:07:30.565696 | controller | skipping: Conditional result was False
2025-12-25 00:07:30.575653 | 
2025-12-25 00:07:30.575802 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-12-25 00:07:30.601361 | controller | skipping: Conditional result was False
2025-12-25 00:07:30.612316 | 
2025-12-25 00:07:30.612485 | TASK [use-our-mirror : Create the podman configuration directory]
2025-12-25 00:07:30.638090 | controller | skipping: Conditional result was False
2025-12-25 00:07:30.647424 | 
2025-12-25 00:07:30.647582 | TASK [use-our-mirror : Copy the podman configuration]
2025-12-25 00:07:30.673464 | controller | skipping: Conditional result was False
2025-12-25 00:07:30.683091 | 
2025-12-25 00:07:30.683267 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-12-25 00:07:30.709482 | controller | skipping: Conditional result was False
2025-12-25 00:07:30.719000 | 
2025-12-25 00:07:30.719193 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-12-25 00:07:30.744713 | controller | skipping: Conditional result was False
2025-12-25 00:07:30.762824 | 
2025-12-25 00:07:30.762971 | TASK [Disable Fedora Modular]
2025-12-25 00:07:31.046268 | controller | changed
2025-12-25 00:07:31.055093 | 
2025-12-25 00:07:31.055245 | TASK [Enable EPEL]
2025-12-25 00:07:31.081475 | controller | skipping: Conditional result was False
2025-12-25 00:07:31.090821 | 
2025-12-25 00:07:31.090950 | TASK [Register the RHEL node]
2025-12-25 00:07:31.644609 | 
2025-12-25 00:07:31.644814 | TASK [Show the subscription-manager status]
2025-12-25 00:07:32.188735 | controller | skipping: Conditional result was False
2025-12-25 00:07:32.198874 | 
2025-12-25 00:07:32.198987 | TASK [Enable EPEL on RHEL]
2025-12-25 00:07:32.756071 | controller | skipping: Conditional result was False
2025-12-25 00:07:32.764601 | 
2025-12-25 00:07:32.764750 | TASK [Install git and tox]
2025-12-25 00:08:42.640125 | controller | changed
2025-12-25 00:08:42.650503 | 
2025-12-25 00:08:42.650974 | TASK [include_role : prepare-workspace]
2025-12-25 00:08:42.688272 | controller | ok
2025-12-25 00:08:42.708905 | 
2025-12-25 00:08:42.709008 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-25 00:08:42.982667 | controller | ok
2025-12-25 00:08:42.998065 | 
2025-12-25 00:08:42.998208 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-25 00:08:55.831752 | controller | Output suppressed because no_log was given
2025-12-25 00:08:55.846653 | 
2025-12-25 00:08:55.846804 | TASK [include_role : prepare-workspace-openshift]
2025-12-25 00:08:55.864211 | controller | skipping: Conditional result was False
2025-12-25 00:08:55.898170 | 
2025-12-25 00:08:55.898266 | PLAY [all:!appliance]
2025-12-25 00:08:55.915086 | 
2025-12-25 00:08:55.915205 | TASK [Run add-build-sshkey role (RSA)]
2025-12-25 00:08:55.935103 | controller | ok
2025-12-25 00:08:55.950919 | 
2025-12-25 00:08:55.951059 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-25 00:08:56.210008 | controller -> localhost | ok
2025-12-25 00:08:56.219451 | 
2025-12-25 00:08:56.219559 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-25 00:08:56.252210 | controller | ok
2025-12-25 00:08:56.267623 | controller | included: /var/lib/zuul/builds/5eab4088b67d47e9bfbd747b1bee96b0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-25 00:08:56.274095 | 
2025-12-25 00:08:56.274202 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-25 00:08:56.911371 | controller -> localhost | Generating public/private rsa key pair.
2025-12-25 00:08:56.911668 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/5eab4088b67d47e9bfbd747b1bee96b0/work/5eab4088b67d47e9bfbd747b1bee96b0_id_rsa.
2025-12-25 00:08:56.911700 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/5eab4088b67d47e9bfbd747b1bee96b0/work/5eab4088b67d47e9bfbd747b1bee96b0_id_rsa.pub.
2025-12-25 00:08:56.911721 | controller -> localhost | The key fingerprint is:
2025-12-25 00:08:56.911740 | controller -> localhost | SHA256:7+vRM4MOCxCNmpcARfGqrjqDyJiFeaTZracqpmFE/Mw zuul-build-sshkey
2025-12-25 00:08:56.911759 | controller -> localhost | The key's randomart image is:
2025-12-25 00:08:56.911778 | controller -> localhost | +---[RSA 2048]----+
2025-12-25 00:08:56.911796 | controller -> localhost | |.o+.             |
2025-12-25 00:08:56.911814 | controller -> localhost | |.. . o           |
2025-12-25 00:08:56.911832 | controller -> localhost | | o. + .          |
2025-12-25 00:08:56.911849 | controller -> localhost | |. == o           |
2025-12-25 00:08:56.911866 | controller -> localhost | | O+E+   S        |
2025-12-25 00:08:56.911899 | controller -> localhost | |*.+...   . o     |
2025-12-25 00:08:56.911921 | controller -> localhost | |B= .  . . + =    |
2025-12-25 00:08:56.911942 | controller -> localhost | |@+. .  . = . +   |
2025-12-25 00:08:56.911961 | controller -> localhost | |@=.o    ..=.     |
2025-12-25 00:08:56.911981 | controller -> localhost | +----[SHA256]-----+
2025-12-25 00:08:56.912052 | controller -> localhost | ok: Runtime: 0:00:00.222873
2025-12-25 00:08:56.924945 | 
2025-12-25 00:08:56.925061 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-25 00:08:56.963145 | controller | ok
2025-12-25 00:08:56.974742 | controller | included: /var/lib/zuul/builds/5eab4088b67d47e9bfbd747b1bee96b0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-25 00:08:56.984412 | 
2025-12-25 00:08:56.984485 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-25 00:08:57.010431 | controller | skipping: Conditional result was False
2025-12-25 00:08:57.018201 | 
2025-12-25 00:08:57.018288 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-25 00:08:57.447044 | controller | changed
2025-12-25 00:08:57.460006 | 
2025-12-25 00:08:57.460176 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-25 00:08:57.699161 | controller | ok
2025-12-25 00:08:57.705857 | 
2025-12-25 00:08:57.705943 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-25 00:08:58.338317 | controller | changed
2025-12-25 00:08:58.348046 | 
2025-12-25 00:08:58.348168 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-25 00:08:58.978662 | controller | changed
2025-12-25 00:08:58.984703 | 
2025-12-25 00:08:58.984788 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-25 00:08:59.019468 | controller | skipping: Conditional result was False
2025-12-25 00:08:59.029179 | 
2025-12-25 00:08:59.029339 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-25 00:08:59.390943 | controller -> localhost | changed
2025-12-25 00:08:59.407813 | 
2025-12-25 00:08:59.407952 | TASK [add-build-sshkey : Add back temp key]
2025-12-25 00:08:59.718930 | controller -> localhost | Identity added: /var/lib/zuul/builds/5eab4088b67d47e9bfbd747b1bee96b0/work/5eab4088b67d47e9bfbd747b1bee96b0_id_rsa (zuul-build-sshkey)
2025-12-25 00:08:59.719166 | controller -> localhost | ok: Runtime: 0:00:00.014556
2025-12-25 00:08:59.728464 | 
2025-12-25 00:08:59.728555 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-25 00:09:00.021157 | controller | ok
2025-12-25 00:09:00.026907 | 
2025-12-25 00:09:00.026981 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-25 00:09:00.066212 | controller | skipping: Conditional result was False
2025-12-25 00:09:00.088196 | 
2025-12-25 00:09:00.088395 | TASK [Run add-build-sshkey role (ECDSA)]
2025-12-25 00:09:00.131904 | controller | ok
2025-12-25 00:09:00.150120 | 
2025-12-25 00:09:00.150199 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-25 00:09:00.450343 | controller -> localhost | ok
2025-12-25 00:09:00.458329 | 
2025-12-25 00:09:00.458438 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-25 00:09:00.488840 | controller | ok
2025-12-25 00:09:00.503462 | controller | included: /var/lib/zuul/builds/5eab4088b67d47e9bfbd747b1bee96b0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-25 00:09:00.512235 | 
2025-12-25 00:09:00.512335 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-25 00:09:00.784268 | controller -> localhost | Generating public/private ecdsa key pair.
2025-12-25 00:09:00.784537 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/5eab4088b67d47e9bfbd747b1bee96b0/work/5eab4088b67d47e9bfbd747b1bee96b0_id_ecdsa.
2025-12-25 00:09:00.784577 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/5eab4088b67d47e9bfbd747b1bee96b0/work/5eab4088b67d47e9bfbd747b1bee96b0_id_ecdsa.pub.
2025-12-25 00:09:00.784617 | controller -> localhost | The key fingerprint is:
2025-12-25 00:09:00.784646 | controller -> localhost | SHA256:AW+E7k1wb+CJJAF3A9q3jDM1FRW3Yp4yfx9hhoNNc2s zuul-build-sshkey
2025-12-25 00:09:00.784677 | controller -> localhost | The key's randomart image is:
2025-12-25 00:09:00.784705 | controller -> localhost | +---[ECDSA 521]---+
2025-12-25 00:09:00.784731 | controller -> localhost | |  ..+o+.+oo..    |
2025-12-25 00:09:00.784758 | controller -> localhost | |   +..+*o  . .   |
2025-12-25 00:09:00.784806 | controller -> localhost | |  . .++=++o + .  |
2025-12-25 00:09:00.784835 | controller -> localhost | |     =o+++o* + . |
2025-12-25 00:09:00.784862 | controller -> localhost | |    +.ooS.+ + E  |
2025-12-25 00:09:00.784887 | controller -> localhost | |     o. .+   = . |
2025-12-25 00:09:00.784912 | controller -> localhost | |          . . .  |
2025-12-25 00:09:00.784936 | controller -> localhost | |           . . . |
2025-12-25 00:09:00.784961 | controller -> localhost | |              .  |
2025-12-25 00:09:00.784985 | controller -> localhost | +----[SHA256]-----+
2025-12-25 00:09:00.785079 | controller -> localhost | ok: Runtime: 0:00:00.014773
2025-12-25 00:09:00.793599 | 
2025-12-25 00:09:00.793953 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-25 00:09:00.817744 | controller | ok
2025-12-25 00:09:00.827783 | controller | included: /var/lib/zuul/builds/5eab4088b67d47e9bfbd747b1bee96b0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-25 00:09:00.840098 | 
2025-12-25 00:09:00.840202 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-25 00:09:00.865451 | controller | skipping: Conditional result was False
2025-12-25 00:09:00.872024 | 
2025-12-25 00:09:00.872108 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-25 00:09:01.127592 | controller | changed
2025-12-25 00:09:01.143185 | 
2025-12-25 00:09:01.143349 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-25 00:09:01.382230 | controller | ok
2025-12-25 00:09:01.390093 | 
2025-12-25 00:09:01.390189 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-25 00:09:02.030692 | controller | changed
2025-12-25 00:09:02.046437 | 
2025-12-25 00:09:02.046609 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-25 00:09:02.677376 | controller | changed
2025-12-25 00:09:02.689914 | 
2025-12-25 00:09:02.690070 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-25 00:09:02.707079 | controller | skipping: Conditional result was False
2025-12-25 00:09:02.720758 | 
2025-12-25 00:09:02.720905 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-25 00:09:03.002234 | controller -> localhost | changed
2025-12-25 00:09:03.015355 | 
2025-12-25 00:09:03.015467 | TASK [add-build-sshkey : Add back temp key]
2025-12-25 00:09:03.327965 | controller -> localhost | Identity added: /var/lib/zuul/builds/5eab4088b67d47e9bfbd747b1bee96b0/work/5eab4088b67d47e9bfbd747b1bee96b0_id_ecdsa (zuul-build-sshkey)
2025-12-25 00:09:03.328241 | controller -> localhost | ok: Runtime: 0:00:00.008205
2025-12-25 00:09:03.341576 | 
2025-12-25 00:09:03.341686 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-25 00:09:03.539873 | controller | ok
2025-12-25 00:09:03.546524 | 
2025-12-25 00:09:03.546637 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-25 00:09:03.584928 | controller | skipping: Conditional result was False
2025-12-25 00:09:03.611990 | 
2025-12-25 00:09:03.612128 | TASK [include_role : remove-zuul-sshkey]
2025-12-25 00:09:03.627429 | controller | skipping: Conditional result was False
2025-12-25 00:09:03.634941 | 
2025-12-25 00:09:03.635054 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-25 00:09:03.851171 | controller | ok: "logs"
2025-12-25 00:09:03.851445 | controller | ok: All items complete
2025-12-25 00:09:03.851477 | 
2025-12-25 00:09:04.054735 | controller | ok: "artifacts"
2025-12-25 00:09:04.246714 | controller | ok: "docs"
2025-12-25 00:09:04.272467 | 
2025-12-25 00:09:04.272704 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-25 00:09:04.530190 | controller | changed: "logs"
2025-12-25 00:09:04.736166 | controller | changed: "artifacts"
2025-12-25 00:09:04.941119 | controller | changed: "docs"
2025-12-25 00:09:04.998533 | 
2025-12-25 00:09:04.998677 | PLAY RECAP
2025-12-25 00:09:04.998743 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-12-25 00:09:04.998782 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-25 00:09:04.998809 | 
2025-12-25 00:09:05.125335 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-12-25 00:09:05.126290 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-25 00:09:05.729482 | 
2025-12-25 00:09:05.729664 | PLAY [all]
2025-12-25 00:09:05.756546 | 
2025-12-25 00:09:05.756700 | TASK [Install binary dependencies]
2025-12-25 00:09:05.798313 | controller | ok
2025-12-25 00:09:05.819676 | 
2025-12-25 00:09:05.819820 | TASK [bindep : Include find tasks]
2025-12-25 00:09:05.850114 | controller | ok
2025-12-25 00:09:05.865438 | controller | included: /var/lib/zuul/builds/5eab4088b67d47e9bfbd747b1bee96b0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-12-25 00:09:05.874652 | 
2025-12-25 00:09:05.874801 | TASK [bindep : Look for bindep.txt]
2025-12-25 00:09:06.222312 | controller | ok
2025-12-25 00:09:06.241304 | 
2025-12-25 00:09:06.241496 | TASK [bindep : Define bindep_file fact]
2025-12-25 00:09:06.267678 | controller | skipping: Conditional result was False
2025-12-25 00:09:06.274372 | 
2025-12-25 00:09:06.274471 | TASK [bindep : Look for other-requirements.txt]
2025-12-25 00:09:06.471966 | controller | ok
2025-12-25 00:09:06.485740 | 
2025-12-25 00:09:06.485875 | TASK [bindep : Define bindep_file fact]
2025-12-25 00:09:06.519919 | controller | skipping: Conditional result was False
2025-12-25 00:09:06.533099 | 
2025-12-25 00:09:06.533276 | TASK [bindep : Look for bindep fallback file]
2025-12-25 00:09:06.580222 | controller | skipping: Conditional result was False
2025-12-25 00:09:06.589961 | 
2025-12-25 00:09:06.590104 | TASK [bindep : Define bindep_file fact]
2025-12-25 00:09:06.624970 | controller | skipping: Conditional result was False
2025-12-25 00:09:06.633654 | 
2025-12-25 00:09:06.633749 | TASK [bindep : Include bindep tasks]
2025-12-25 00:09:06.668672 | controller | skipping: Conditional result was False
2025-12-25 00:09:06.675634 | 
2025-12-25 00:09:06.675719 | TASK [bindep : Include install tasks]
2025-12-25 00:09:06.700108 | controller | skipping: Conditional result was False
2025-12-25 00:09:06.708247 | 
2025-12-25 00:09:06.708392 | LOOP [bindep : Include package tasks]
2025-12-25 00:09:06.785975 | 
2025-12-25 00:09:06.786189 | TASK [Run test-setup role]
2025-12-25 00:09:06.812248 | controller | ok
2025-12-25 00:09:06.841244 | 
2025-12-25 00:09:06.841418 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-12-25 00:09:07.062106 | controller | ok
2025-12-25 00:09:07.076465 | 
2025-12-25 00:09:07.076633 | TASK [test-setup : Run tools/test-setup.sh]
2025-12-25 00:09:07.632992 | controller | skipping: Conditional result was False
2025-12-25 00:09:07.665628 | 
2025-12-25 00:09:07.665745 | PLAY RECAP
2025-12-25 00:09:07.665789 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-25 00:09:07.665809 | 
2025-12-25 00:09:07.774283 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-25 00:09:07.775312 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-12-25 00:09:08.387494 | 
2025-12-25 00:09:08.387639 | PLAY [controller]
2025-12-25 00:09:08.409211 | 
2025-12-25 00:09:08.409356 | TASK [Create the /root directory]
2025-12-25 00:09:08.818128 | controller | ok
2025-12-25 00:09:08.824528 | 
2025-12-25 00:09:08.824624 | TASK [Install glibc-langpack-en]
2025-12-25 00:09:12.694452 | controller | ok: Nothing to do
2025-12-25 00:09:12.701091 | 
2025-12-25 00:09:12.701226 | TASK [Ensure controller directory exists]
2025-12-25 00:09:12.925794 | controller | changed
2025-12-25 00:09:12.939981 | 
2025-12-25 00:09:12.940192 | TASK [Install container runtime]
2025-12-25 00:09:13.014311 | controller | ok
2025-12-25 00:09:13.064918 | 
2025-12-25 00:09:13.065076 | LOOP [ensure-docker : Gather variables for each operating system]
2025-12-25 00:09:13.109697 | controller | ok: "/var/lib/zuul/builds/5eab4088b67d47e9bfbd747b1bee96b0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-12-25 00:09:13.127704 | 
2025-12-25 00:09:13.127902 | TASK [ensure-docker : Sanity check for distro selection]
2025-12-25 00:09:13.152844 | controller | skipping: Conditional result was False
2025-12-25 00:09:13.162179 | 
2025-12-25 00:09:13.162337 | TASK [ensure-docker : Sanity check for distro install]
2025-12-25 00:09:13.187871 | controller | skipping: Conditional result was False
2025-12-25 00:09:13.197999 | 
2025-12-25 00:09:13.198228 | TASK [ensure-docker : Sanity check for upstream install]
2025-12-25 00:09:13.223810 | controller | skipping: Conditional result was False
2025-12-25 00:09:13.232795 | 
2025-12-25 00:09:13.232942 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-12-25 00:09:13.258213 | controller | skipping: Conditional result was False
2025-12-25 00:09:13.270227 | 
2025-12-25 00:09:13.270355 | TASK [ensure-docker : Include tasks for docker install]
2025-12-25 00:09:13.303834 | controller | ok
2025-12-25 00:09:13.315852 | controller | included: /var/lib/zuul/builds/5eab4088b67d47e9bfbd747b1bee96b0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-12-25 00:09:13.324956 | 
2025-12-25 00:09:13.325161 | TASK [ensure-docker : Not Implemented]
2025-12-25 00:09:13.349773 | controller | skipping: Conditional result was False
2025-12-25 00:09:13.356975 | 
2025-12-25 00:09:13.357071 | TASK [Set up docker mirrors]
2025-12-25 00:09:13.376425 | controller | ok
2025-12-25 00:09:13.397977 | 
2025-12-25 00:09:13.398121 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-12-25 00:09:13.432341 | controller | skipping: Conditional result was False
2025-12-25 00:09:13.439170 | 
2025-12-25 00:09:13.439265 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-12-25 00:09:13.472959 | controller | skipping: Conditional result was False
2025-12-25 00:09:13.479474 | 
2025-12-25 00:09:13.479549 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-12-25 00:09:13.503234 | controller | skipping: Conditional result was False
2025-12-25 00:09:13.511001 | 
2025-12-25 00:09:13.511146 | TASK [use-docker-mirror : Set up docker mirrors]
2025-12-25 00:09:13.537906 | controller | skipping: Conditional result was False
2025-12-25 00:09:13.552097 | 
2025-12-25 00:09:13.552203 | TASK [Add all repositories]
2025-12-25 00:09:13.572378 | controller | ok
2025-12-25 00:09:13.598902 | 
2025-12-25 00:09:13.599057 | LOOP [ensure-package-repositories : Install repositories]
2025-12-25 00:09:13.629676 | controller | ok: "/var/lib/zuul/builds/5eab4088b67d47e9bfbd747b1bee96b0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-12-25 00:09:13.629982 | controller | ok: All items complete
2025-12-25 00:09:13.630033 | 
2025-12-25 00:09:13.644184 | controller | included: /var/lib/zuul/builds/5eab4088b67d47e9bfbd747b1bee96b0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-12-25 00:09:13.680846 | 
2025-12-25 00:09:13.680995 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-12-25 00:09:14.025198 | controller | changed
2025-12-25 00:09:14.040806 | 
2025-12-25 00:09:14.040997 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-12-25 00:09:14.982469 | controller | changed:
2025-12-25 00:09:14.982773 | controller | {
2025-12-25 00:09:14.982848 | 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-25 00:09:14.982957 | controller | }
2025-12-25 00:09:15.000803 | 
2025-12-25 00:09:15.000928 | LOOP [ensure-package-repositories : Import GPG keys]
2025-12-25 00:09:15.849035 | controller | changed:
2025-12-25 00:09:15.849228 | controller | {
2025-12-25 00:09:15.849266 | 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-25 00:09:15.849297 | controller | }
2025-12-25 00:09:15.849336 | controller | changed: All items complete
2025-12-25 00:09:15.849364 | 
2025-12-25 00:09:15.871696 | 
2025-12-25 00:09:15.872097 | TASK [ensure-package-repositories : Remove GPG temp directory]
2025-12-25 00:09:16.091642 | controller | changed
2025-12-25 00:09:16.102381 | 
2025-12-25 00:09:16.102525 | LOOP [ensure-package-repositories : Add repositories]
2025-12-25 00:09:16.446409 | controller | changed:
2025-12-25 00:09:16.446715 | controller | {
2025-12-25 00:09:16.446747 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2025-12-25 00:09:16.446781 | controller |   "description": "Docker CE Stable - $basearch",
2025-12-25 00:09:16.446799 | controller |   "gpgcheck": true,
2025-12-25 00:09:16.446816 | controller |   "name": "docker-ce-stable"
2025-12-25 00:09:16.446836 | controller | }
2025-12-25 00:09:16.473072 | 
2025-12-25 00:09:16.473298 | TASK [ensure-docker : Install pre-reqs]
2025-12-25 00:09:19.246945 | controller | ok: Nothing to do
2025-12-25 00:09:19.255950 | 
2025-12-25 00:09:19.256064 | TASK [ensure-docker : Remove packages]
2025-12-25 00:09:20.583212 | controller | ok: Nothing to do
2025-12-25 00:09:20.591387 | 
2025-12-25 00:09:20.591503 | TASK [ensure-docker : Install upstream docker using package]
2025-12-25 00:10:07.201430 | controller | changed
2025-12-25 00:10:07.210924 | 
2025-12-25 00:10:07.211044 | TASK [ensure-docker : Setup docker]
2025-12-25 00:10:07.235545 | controller | ok
2025-12-25 00:10:07.249515 | controller | included: /var/lib/zuul/builds/5eab4088b67d47e9bfbd747b1bee96b0/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2025-12-25 00:10:07.261389 | 
2025-12-25 00:10:07.261473 | TASK [ensure-docker : Ensure "docker" group exists]
2025-12-25 00:10:07.647643 | controller | ok
2025-12-25 00:10:07.661992 | 
2025-12-25 00:10:07.662182 | TASK [ensure-docker : Add user to docker group]
2025-12-25 00:10:08.157004 | controller | changed
2025-12-25 00:10:08.166888 | 
2025-12-25 00:10:08.167043 | TASK [Update docker daemon proxy configuration]
2025-12-25 00:10:08.197498 | controller | skipping: Conditional result was False
2025-12-25 00:10:08.207910 | 
2025-12-25 00:10:08.208090 | TASK [Lower default MTU]
2025-12-25 00:10:08.238123 | controller | skipping: Conditional result was False
2025-12-25 00:10:08.249251 | 
2025-12-25 00:10:08.249398 | TASK [ensure-docker : Get the status of the docket.socket unit]
2025-12-25 00:10:08.636973 | controller | inactive
2025-12-25 00:10:08.827509 | controller | ok: Runtime: 0:00:00.017568
2025-12-25 00:10:08.835503 | 
2025-12-25 00:10:08.835683 | TASK [ensure-docker : Get the status of the docket unit]
2025-12-25 00:10:09.083593 | controller | inactive
2025-12-25 00:10:09.373681 | controller | ok: Runtime: 0:00:00.012075
2025-12-25 00:10:09.380502 | 
2025-12-25 00:10:09.380592 | TASK [ensure-docker : Restart docker]
2025-12-25 00:10:09.412594 | Trigger a Docker restart
2025-12-25 00:10:09.418907 | 
2025-12-25 00:10:09.418985 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2025-12-25 00:10:09.445498 | 
2025-12-25 00:10:09.445633 | TASK [ensure-docker : Install docker-compose]
2025-12-25 00:10:09.465876 | controller | skipping: Conditional result was False
2025-12-25 00:10:09.473938 | 
2025-12-25 00:10:09.474031 | TASK [ensure-docker : Flush handlers before role exit]
2025-12-25 00:10:09.498227 | 
2025-12-25 00:10:09.498403 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2025-12-25 00:10:10.770430 | controller | changed
2025-12-25 00:10:10.787823 | 
2025-12-25 00:10:10.787986 | TASK [ensure-docker : Assure docker service is running]
2025-12-25 00:10:12.618586 | controller | changed
2025-12-25 00:10:12.641757 | 
2025-12-25 00:10:12.641940 | TASK [ensure-docker : Assure docker.socket service is running]
2025-12-25 00:10:13.227547 | controller | ok
2025-12-25 00:10:13.237650 | 
2025-12-25 00:10:13.237785 | TASK [ensure-docker : Correct group ownership on docker sock]
2025-12-25 00:10:13.842166 | controller | ok
2025-12-25 00:10:13.848804 | 
2025-12-25 00:10:13.848916 | TASK [ensure-docker : Validate ability to talk with docker]
2025-12-25 00:10:14.433666 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2025-12-25 00:10:14.895459 | controller | ok: Runtime: 0:00:00.048859
2025-12-25 00:10:14.902329 | 
2025-12-25 00:10:14.902463 | TASK [ensure-docker : Get version details]
2025-12-25 00:10:15.494903 | controller | Client: Docker Engine - Community
2025-12-25 00:10:15.494944 | controller |  Version:           24.0.7
2025-12-25 00:10:15.494997 | controller |  API version:       1.43
2025-12-25 00:10:15.495004 | controller |  Go version:        go1.20.10
2025-12-25 00:10:15.495014 | controller |  Git commit:        afdd53b
2025-12-25 00:10:15.495019 | controller |  Built:             Thu Oct 26 09:09:39 2023
2025-12-25 00:10:15.495031 | controller |  OS/Arch:           linux/amd64
2025-12-25 00:10:15.495037 | controller |  Context:           default
2025-12-25 00:10:15.495043 | controller |
2025-12-25 00:10:15.495048 | controller | Server: Docker Engine - Community
2025-12-25 00:10:15.495053 | controller |  Engine:
2025-12-25 00:10:15.495059 | controller |   Version:          24.0.7
2025-12-25 00:10:15.495076 | controller |   API version:      1.43 (minimum version 1.12)
2025-12-25 00:10:15.495087 | controller |   Go version:       go1.20.10
2025-12-25 00:10:15.495092 | controller |   Git commit:       311b9ff
2025-12-25 00:10:15.495096 | controller |   Built:            Thu Oct 26 09:08:11 2023
2025-12-25 00:10:15.495100 | controller |   OS/Arch:          linux/amd64
2025-12-25 00:10:15.495104 | controller |   Experimental:     false
2025-12-25 00:10:15.495109 | controller |  containerd:
2025-12-25 00:10:15.495113 | controller |   Version:          1.6.25
2025-12-25 00:10:15.495117 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2025-12-25 00:10:15.495121 | controller |  runc:
2025-12-25 00:10:15.495125 | controller |   Version:          1.1.10
2025-12-25 00:10:15.495129 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2025-12-25 00:10:15.495140 | controller |  docker-init:
2025-12-25 00:10:15.495147 | controller |   Version:          0.19.0
2025-12-25 00:10:15.496301 | controller |   GitCommit:        de40ad0
2025-12-25 00:10:15.945616 | controller | ok: Runtime: 0:00:00.091116
2025-12-25 00:10:15.952458 | 
2025-12-25 00:10:15.952567 | TASK [ensure-docker : Dump installed docker details]
2025-12-25 00:10:15.984420 | {'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-25 00:10:15.405347', 'end': '2025-12-25 00:10:15.496463', 'delta': '0:00:00.091116', 'msg': '', 'zuul_log_id': 'fa163ec2-ffbe-f799-603c-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-25 00:10:15.990761 | 
2025-12-25 00:10:15.990847 | TASK [Use buildset registry]
2025-12-25 00:10:16.004982 | controller | skipping: Conditional result was False
2025-12-25 00:10:16.017110 | 
2025-12-25 00:10:16.017240 | TASK [Ensure python3.8 is present]
2025-12-25 00:10:16.031732 | controller | skipping: Conditional result was False
2025-12-25 00:10:16.038853 | 
2025-12-25 00:10:16.038958 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-12-25 00:10:16.059084 | controller | ok
2025-12-25 00:10:16.082050 | 
2025-12-25 00:10:16.082187 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-12-25 00:10:18.120592 | controller | ok: Nothing to do
2025-12-25 00:10:18.128380 | 
2025-12-25 00:10:18.128502 | TASK [our-ensure-python : Also install python3-devel]
2025-12-25 00:10:30.160171 | controller | changed
2025-12-25 00:10:30.172032 | 
2025-12-25 00:10:30.172142 | TASK [Run ensure-virtualenv role]
2025-12-25 00:10:30.191649 | controller | ok
2025-12-25 00:10:30.213920 | 
2025-12-25 00:10:30.214062 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-12-25 00:10:30.441088 | controller | /usr/bin/virtualenv
2025-12-25 00:10:30.745196 | controller | ok: Runtime: 0:00:00.005272
2025-12-25 00:10:30.753152 | 
2025-12-25 00:10:30.753231 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-12-25 00:10:30.787538 | controller | skipping: Conditional result was False
2025-12-25 00:10:30.787823 | controller | changed: All items complete
2025-12-25 00:10:30.787858 | 
2025-12-25 00:10:30.803802 | 
2025-12-25 00:10:30.803916 | TASK [Find the full path of the Python interpreter]
2025-12-25 00:10:31.017376 | controller | /usr/bin/python3.8
2025-12-25 00:10:31.342431 | controller | ok
2025-12-25 00:10:31.349382 | 
2025-12-25 00:10:31.349666 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-12-25 00:10:32.644918 | controller | created virtual environment CPython3.8.18.final.0-64 in 746ms
2025-12-25 00:10:32.662441 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-12-25 00:10:32.662457 | 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-25 00:10:32.662472 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-12-25 00:10:32.662484 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-12-25 00:10:32.885491 | controller | changed
2025-12-25 00:10:32.899471 | 
2025-12-25 00:10:32.899649 | TASK [Set selinux package]
2025-12-25 00:10:32.940251 | controller | ok
2025-12-25 00:10:32.954438 | 
2025-12-25 00:10:32.954653 | TASK [Set selinux package (Fedora)]
2025-12-25 00:10:33.011072 | controller | ok
2025-12-25 00:10:33.019608 | 
2025-12-25 00:10:33.019771 | TASK [Install selinux into virtualenv]
2025-12-25 00:11:11.835988 | controller | Collecting selinux-please-lie-to-me
2025-12-25 00:11:24.090079 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-12-25 00:11:24.458857 | controller | Collecting setuptools<50.0.0
2025-12-25 00:11:24.464322 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-12-25 00:11:24.509516 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 20.4 MB/s eta 0:00:00
2025-12-25 00:11:24.588152 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-12-25 00:11:24.588308 | controller |   Attempting uninstall: setuptools
2025-12-25 00:11:24.588980 | controller |     Found existing installation: setuptools 62.6.0
2025-12-25 00:11:24.664573 | controller |     Uninstalling setuptools-62.6.0:
2025-12-25 00:11:24.673300 | controller |       Successfully uninstalled setuptools-62.6.0
2025-12-25 00:11:24.986967 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-12-25 00:11:36.466824 | controller |
2025-12-25 00:11:36.538840 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-12-25 00:11:36.538871 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-12-25 00:11:36.603462 | controller | ok: Runtime: 0:01:03.222307
2025-12-25 00:11:36.610265 | 
2025-12-25 00:11:36.610342 | TASK [Install pytest-forked into virtualenv]
2025-12-25 00:11:48.694109 | controller | Collecting pytest-forked
2025-12-25 00:12:00.954608 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-12-25 00:12:01.085355 | controller | Collecting pytest>=3.10
2025-12-25 00:12:01.089859 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2025-12-25 00:12:01.120010 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 12.6 MB/s eta 0:00:00
2025-12-25 00:12:01.161896 | controller | Collecting py
2025-12-25 00:12:01.165946 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-12-25 00:12:01.174535 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 15.3 MB/s eta 0:00:00
2025-12-25 00:12:01.222209 | controller | Collecting pluggy<2,>=1.5
2025-12-25 00:12:01.226063 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-12-25 00:12:01.276271 | controller | Collecting tomli>=1
2025-12-25 00:12:01.279801 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2025-12-25 00:12:01.326390 | controller | Collecting packaging
2025-12-25 00:12:01.332073 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-12-25 00:12:01.339838 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 11.5 MB/s eta 0:00:00
2025-12-25 00:12:01.361548 | controller | Collecting iniconfig
2025-12-25 00:12:01.370243 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-12-25 00:12:01.404709 | controller | Collecting exceptiongroup>=1.0.0rc8
2025-12-25 00:12:01.408088 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2025-12-25 00:12:01.461911 | controller | Collecting typing-extensions>=4.6.0
2025-12-25 00:12:01.465571 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2025-12-25 00:12:01.470474 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 13.2 MB/s eta 0:00:00
2025-12-25 00:12:01.530011 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-12-25 00:12:01.937597 | 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-25 00:12:01.940973 | controller |
2025-12-25 00:12:02.013708 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-12-25 00:12:02.013758 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-12-25 00:12:02.155430 | controller | ok: Runtime: 0:00:25.211221
2025-12-25 00:12:02.163846 | 
2025-12-25 00:12:02.163982 | TASK [Update pip]
2025-12-25 00:12:02.776936 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2025-12-25 00:12:10.265863 | controller | Collecting pip
2025-12-25 00:12:34.553287 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2025-12-25 00:12:34.597290 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 45.9 MB/s eta 0:00:00
2025-12-25 00:12:34.653429 | controller | Installing collected packages: pip
2025-12-25 00:12:34.653652 | controller |   Attempting uninstall: pip
2025-12-25 00:12:34.654285 | controller |     Found existing installation: pip 22.2.2
2025-12-25 00:12:34.831850 | controller |     Uninstalling pip-22.2.2:
2025-12-25 00:12:34.847519 | controller |       Successfully uninstalled pip-22.2.2
2025-12-25 00:12:35.631651 | controller | Successfully installed pip-25.0.1
2025-12-25 00:12:35.803096 | controller | ok: Runtime: 0:00:33.230117
2025-12-25 00:12:35.810868 | 
2025-12-25 00:12:35.810959 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-12-25 00:12:36.033265 | controller | changed
2025-12-25 00:12:36.049561 | 
2025-12-25 00:12:36.049751 | TASK [Install ansible into virtualenv]
2025-12-25 00:12:36.593451 | controller | Processing ./src/github.com/ansible/ansible
2025-12-25 00:12:36.594384 | controller |   Preparing metadata (setup.py): started
2025-12-25 00:12:37.022360 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-12-25 00:12:46.841011 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2025-12-25 00:13:11.069498 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-12-25 00:13:11.134522 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2025-12-25 00:13:11.138848 | 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-25 00:13:11.392024 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2025-12-25 00:13:11.396570 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-12-25 00:13:11.420309 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (25.0)
2025-12-25 00:13:11.559293 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2025-12-25 00:13:11.563911 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2025-12-25 00:13:11.576823 | 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-25 00:13:11.654513 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2025-12-25 00:13:11.659385 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2025-12-25 00:13:11.689098 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2025-12-25 00:13:11.693142 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-12-25 00:13:11.715425 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2025-12-25 00:13:11.780402 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 76.0 MB/s eta 0:00:00
2025-12-25 00:13:11.785412 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-12-25 00:13:11.801462 | 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-25 00:13:11.818535 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 46.9 MB/s eta 0:00:00
2025-12-25 00:13:11.825091 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2025-12-25 00:13:11.839677 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2025-12-25 00:13:11.851019 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-12-25 00:13:11.872483 | controller | Building wheels for collected packages: ansible-base
2025-12-25 00:13:11.873029 | controller |   Building wheel for ansible-base (setup.py): started
2025-12-25 00:13:13.058683 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2025-12-25 00:13:13.060554 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=bbf037a32d9159c9ee0e64c9556a9251a4aab98023a60fa20571103af20baa5a
2025-12-25 00:13:13.060781 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-xck61vrb/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2025-12-25 00:13:13.070776 | controller | Successfully built ansible-base
2025-12-25 00:13:13.104467 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2025-12-25 00:13:14.442129 | 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-25 00:13:14.618586 | controller | ok: Runtime: 0:00:38.233864
2025-12-25 00:13:14.627254 | 
2025-12-25 00:13:14.627388 | TASK [Setup download-artifact-fork role]
2025-12-25 00:13:14.661632 | controller | ok
2025-12-25 00:13:14.685623 | 
2025-12-25 00:13:14.685857 | TASK [download-artifact-fork : Parse inventory response]
2025-12-25 00:13:14.728614 | controller | ok
2025-12-25 00:13:14.736503 | 
2025-12-25 00:13:14.736634 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-12-25 00:13:14.773237 | controller | skipping: Conditional result was False
2025-12-25 00:13:14.780723 | 
2025-12-25 00:13:14.780807 | TASK [download-artifact-fork : Parse build response]
2025-12-25 00:13:14.817270 | controller | skipping: Conditional result was False
2025-12-25 00:13:14.825662 | 
2025-12-25 00:13:14.825804 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-12-25 00:13:15.044801 | controller | changed
2025-12-25 00:13:15.053824 | 
2025-12-25 00:13:15.054070 | LOOP [download-artifact-fork : Download archive by type]
2025-12-25 00:13:15.084028 | controller | skipping: Conditional result was False
2025-12-25 00:13:15.084321 | 
2025-12-25 00:13:16.227725 | controller | changed: OK (20177 bytes)
2025-12-25 00:13:16.244961 | 
2025-12-25 00:13:16.245135 | TASK [deploy-artifacts : define __collections]
2025-12-25 00:13:16.268693 | controller | ok
2025-12-25 00:13:16.276492 | 
2025-12-25 00:13:16.276580 | LOOP [deploy-artifacts : Create list of collections]
2025-12-25 00:13:16.302785 | controller | skipping: Conditional result was False
2025-12-25 00:13:16.328851 | controller | ok:
2025-12-25 00:13:16.329005 | controller | {
2025-12-25 00:13:16.329155 | controller |   "branch": "main",
2025-12-25 00:13:16.329194 | controller |   "job": "build-ansible-collection",
2025-12-25 00:13:16.329221 | controller |   "metadata": {
2025-12-25 00:13:16.329256 | controller |     "type": "ansible_collection",
2025-12-25 00:13:16.329293 | controller |     "version": "5.0.0"
2025-12-25 00:13:16.329319 | controller |   },
2025-12-25 00:13:16.329343 | controller |   "name": "ansible.network",
2025-12-25 00:13:16.329367 | controller |   "project": "ansible-collections/ansible.network",
2025-12-25 00:13:16.329391 | controller |   "url": "https://dfe21489d505774bd8f2-427a707314c53443d14073c23b4eadd0.ssl.cf1.rackcdn.com/ansible/2c07a7ac651546f5a0f88c2377dbcaec/artifacts/ansible-network-5.0.0.tar.gz"
2025-12-25 00:13:16.329425 | controller | }
2025-12-25 00:13:16.345724 | 
2025-12-25 00:13:16.345836 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-12-25 00:13:16.912778 | controller | Starting galaxy collection install process
2025-12-25 00:13:16.913243 | controller | Process install dependency map
2025-12-25 00:22:14.253185 | controller | Starting collection install process
2025-12-25 00:22:14.253345 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2025-12-25 00:22:14.263674 | controller | ansible.network (5.0.0) was installed successfully
2025-12-25 00:22:14.263738 | controller | Installing 'ansible.netcommon:8.2.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2025-12-25 00:22:14.263868 | 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-43463_yvo2h4/tmp9nfhhn4t
2025-12-25 00:23:16.178023 | controller | ansible.netcommon (8.2.0) was installed successfully
2025-12-25 00:23:16.178131 | controller | Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2025-12-25 00:23:16.178300 | 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-43463_yvo2h4/tmp9nfhhn4t
2025-12-25 00:24:20.405444 | controller | ansible.utils (6.0.0) was installed successfully
2025-12-25 00:24:20.405543 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2025-12-25 00:24:20.405690 | 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-43463_yvo2h4/tmp9nfhhn4t
2025-12-25 00:25:03.869121 | controller | arista.eos (12.0.0) was installed successfully
2025-12-25 00:25:03.869177 | controller | Installing 'cisco.ios:11.2.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2025-12-25 00:25:03.869189 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.2.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-43463_yvo2h4/tmp9nfhhn4t
2025-12-25 00:25:55.266370 | controller | cisco.ios (11.2.0) was installed successfully
2025-12-25 00:25:55.266472 | controller | Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2025-12-25 00:25:55.266610 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-43463_yvo2h4/tmp9nfhhn4t
2025-12-25 00:26:56.268004 | controller | cisco.iosxr (12.1.1) was installed successfully
2025-12-25 00:26:56.268107 | controller | Installing 'cisco.nxos:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2025-12-25 00:26:56.268291 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-43463_yvo2h4/tmp9nfhhn4t
2025-12-25 00:27:48.141160 | controller | cisco.nxos (11.1.1) was installed successfully
2025-12-25 00:27:48.141262 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2025-12-25 00:27:48.141414 | 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-43463_yvo2h4/tmp9nfhhn4t
2025-12-25 00:28:40.243635 | controller | junipernetworks.junos (11.0.0) was installed successfully
2025-12-25 00:28:40.318386 | controller | ok: Runtime: 0:15:23.725137
2025-12-25 00:28:40.335638 | 
2025-12-25 00:28:40.335754 | TASK [podman may need iptables kernel modules]
2025-12-25 00:28:40.867522 | controller | ok: Runtime: 0:00:00.003642
2025-12-25 00:28:40.890206 | 
2025-12-25 00:28:40.890326 | PLAY RECAP
2025-12-25 00:28:40.890377 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2025-12-25 00:28:40.890400 | 
2025-12-25 00:28:41.018493 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-12-25 00:28:41.020350 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-12-25 00:28:41.780273 | 
2025-12-25 00:28:41.780398 | PLAY [controller]
2025-12-25 00:28:41.804742 | 
2025-12-25 00:28:41.804860 | TASK [Copy the galaxy.yml on the controller]
2025-12-25 00:28:42.921808 | controller | changed
2025-12-25 00:28:42.935271 | 
2025-12-25 00:28:42.935425 | TASK [Load information from galaxy.yml]
2025-12-25 00:28:42.975043 | controller | ok
2025-12-25 00:28:42.987569 | 
2025-12-25 00:28:42.987704 | LOOP [Enable FIPS mode]
2025-12-25 00:28:43.023669 | 
2025-12-25 00:28:43.024052 | LOOP [ansible-test : Include branch specific variables.]
2025-12-25 00:28:43.069525 | controller | ok: "/var/lib/zuul/builds/5eab4088b67d47e9bfbd747b1bee96b0/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2025-12-25 00:28:43.069802 | 
2025-12-25 00:28:43.091970 | 
2025-12-25 00:28:43.092199 | TASK [ansible-test : Setup --skip-tags for test_options]
2025-12-25 00:28:43.144424 | controller | ok
2025-12-25 00:28:43.157493 | 
2025-12-25 00:28:43.157646 | TASK [ansible-test : Enable --inventory for network-integration]
2025-12-25 00:28:43.175092 | controller | skipping: Conditional result was False
2025-12-25 00:28:43.182284 | 
2025-12-25 00:28:43.182374 | TASK [ansible-test : Enable --retry-on-error]
2025-12-25 00:28:43.197220 | controller | skipping: Conditional result was False
2025-12-25 00:28:43.211546 | 
2025-12-25 00:28:43.211701 | TASK [ansible-test : Enable --continue-on-error]
2025-12-25 00:28:43.246610 | controller | ok
2025-12-25 00:28:43.255632 | 
2025-12-25 00:28:43.255764 | TASK [ansible-test : Enable --requirements]
2025-12-25 00:28:43.271928 | controller | skipping: Conditional result was False
2025-12-25 00:28:43.283656 | 
2025-12-25 00:28:43.283764 | TASK [ansible-test : Adjust options for unit tests]
2025-12-25 00:28:43.299307 | controller | skipping: Conditional result was False
2025-12-25 00:28:43.309873 | 
2025-12-25 00:28:43.310042 | TASK [ansible-test : Install requirements with unit tests]
2025-12-25 00:28:43.341152 | controller | ok
2025-12-25 00:28:43.347440 | 
2025-12-25 00:28:43.347563 | TASK [ansible-test : Set --skip-test]
2025-12-25 00:28:43.393042 | controller | skipping: Conditional result was False
2025-12-25 00:28:43.400910 | 
2025-12-25 00:28:43.401049 | TASK [ansible-test : Reduce verbosity]
2025-12-25 00:28:43.436466 | controller | ok
2025-12-25 00:28:43.442319 | 
2025-12-25 00:28:43.442392 | TASK [ansible-test : Setup --python option]
2025-12-25 00:28:43.467635 | controller | skipping: Conditional result was False
2025-12-25 00:28:43.506556 | 
2025-12-25 00:28:43.506693 | TASK [ansible-test : Setup --docker option]
2025-12-25 00:28:43.538401 | controller | ok
2025-12-25 00:28:43.544394 | 
2025-12-25 00:28:43.544502 | TASK [ansible-test : check if MANIFEST.json exists]
2025-12-25 00:28:44.129123 | controller | ok
2025-12-25 00:28:44.136835 | 
2025-12-25 00:28:44.136933 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2025-12-25 00:28:44.163278 | controller | skipping: Conditional result was False
2025-12-25 00:28:44.172769 | 
2025-12-25 00:28:44.172899 | TASK [ansible-test : Load information from MANIFEST.json]
2025-12-25 00:28:44.197880 | controller | skipping: Conditional result was False
2025-12-25 00:28:44.209403 | 
2025-12-25 00:28:44.209564 | TASK [ansible-test : Setup location of project for integration tests]
2025-12-25 00:28:44.235696 | controller | skipping: Conditional result was False
2025-12-25 00:28:44.242332 | 
2025-12-25 00:28:44.242400 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2025-12-25 00:28:44.809826 | controller | ok
2025-12-25 00:28:44.819167 | 
2025-12-25 00:28:44.819296 | TASK [ansible-test : Load information from galaxy.yml]
2025-12-25 00:28:44.876272 | controller | ok
2025-12-25 00:28:44.886789 | 
2025-12-25 00:28:44.886900 | TASK [ansible-test : Setup location of project for integration tests]
2025-12-25 00:28:44.932484 | controller | ok
2025-12-25 00:28:44.942884 | 
2025-12-25 00:28:44.943086 | TASK [ansible-test : Setup minimum test requirements]
2025-12-25 00:28:44.975431 | controller | ok
2025-12-25 00:28:44.981349 | 
2025-12-25 00:28:44.981429 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2025-12-25 00:28:45.728525 | controller | ok
2025-12-25 00:28:45.738938 | 
2025-12-25 00:28:45.739062 | TASK [ansible-test : Install python requirements]
2025-12-25 00:29:11.744526 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-12-25 00:29:23.999783 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2025-12-25 00:29:24.035651 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-12-25 00:29:24.040497 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2025-12-25 00:29:24.048975 | 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-25 00:29:24.055276 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2025-12-25 00:29:24.069022 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2025-12-25 00:29:24.112121 | controller | Installing collected packages: pathspec, yamllint
2025-12-25 00:29:24.168662 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2025-12-25 00:29:24.295723 | controller | ok: Runtime: 0:00:37.690213
2025-12-25 00:29:24.304494 | 
2025-12-25 00:29:24.304583 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2025-12-25 00:29:24.555089 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2025-12-25 00:29:24.848291 | controller | ERROR
2025-12-25 00:29:24.848655 | controller | {
2025-12-25 00:29:24.848733 | controller |   "delta": "0:00:00.004004",
2025-12-25 00:29:24.848778 | controller |   "end": "2025-12-25 00:29:24.555226",
2025-12-25 00:29:24.848816 | controller |   "msg": "non-zero return code",
2025-12-25 00:29:24.848852 | controller |   "rc": 1,
2025-12-25 00:29:24.848887 | controller |   "start": "2025-12-25 00:29:24.551222"
2025-12-25 00:29:24.848921 | controller | }
2025-12-25 00:29:24.848970 | controller | ERROR: Ignoring Errors
2025-12-25 00:29:24.862138 | 
2025-12-25 00:29:24.862278 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2025-12-25 00:29:25.422571 | controller | skipping: Conditional result was False
2025-12-25 00:29:25.438086 | 
2025-12-25 00:29:25.438251 | TASK [ansible-test : Enable persistent connection logging]
2025-12-25 00:29:26.161737 | controller | changed: section and option added
2025-12-25 00:29:26.175550 | 
2025-12-25 00:29:26.175699 | TASK [ansible-test : Set persistent ssh_type value]
2025-12-25 00:29:26.579577 | controller | changed: option added
2025-12-25 00:29:26.589459 | 
2025-12-25 00:29:26.589596 | TASK [ansible-test : Set ansible log path]
2025-12-25 00:29:27.157262 | controller | changed: section and option added
2025-12-25 00:29:27.170731 | 
2025-12-25 00:29:27.170948 | TASK [ansible-test : Identify the changed targets]
2025-12-25 00:29:27.724101 | controller | skipping: Conditional result was False
2025-12-25 00:29:27.733087 | 
2025-12-25 00:29:27.733193 | TASK [ansible-test : set_fact]
2025-12-25 00:29:27.799510 | controller | skipping: Conditional result was False
2025-12-25 00:29:27.813293 | 
2025-12-25 00:29:27.813478 | TASK [ansible-test : Set the targets]
2025-12-25 00:29:27.853257 | controller | ok
2025-12-25 00:29:27.864562 | 
2025-12-25 00:29:27.864729 | TASK [ansible-test : set_fact]
2025-12-25 00:29:27.882369 | controller | skipping: Conditional result was False
2025-12-25 00:29:27.893038 | 
2025-12-25 00:29:27.893162 | TASK [ansible-test : List the targets]
2025-12-25 00:29:28.458746 | controller | skipping: Conditional result was False
2025-12-25 00:29:28.473404 | 
2025-12-25 00:29:28.473634 | TASK [ansible-test : set_fact]
2025-12-25 00:29:28.501711 | controller | skipping: Conditional result was False
2025-12-25 00:29:28.517924 | 
2025-12-25 00:29:28.518211 | TASK [ansible-test : set_fact]
2025-12-25 00:29:28.546753 | controller | skipping: Conditional result was False
2025-12-25 00:29:28.562087 | 
2025-12-25 00:29:28.562277 | LOOP [ansible-test : set_fact]
2025-12-25 00:29:28.640881 | 
2025-12-25 00:29:28.641122 | TASK [ansible-test : set_fact]
2025-12-25 00:29:28.666082 | controller | skipping: Conditional result was False
2025-12-25 00:29:28.682576 | 
2025-12-25 00:29:28.682769 | TASK [ansible-test : debug]
2025-12-25 00:29:28.711516 | controller | skipping: Conditional result was False
2025-12-25 00:29:28.720856 | 
2025-12-25 00:29:28.720991 | TASK [ansible-test : debug]
2025-12-25 00:29:28.755780 | About to run: ansible-test sanity --requirements --docker -v
2025-12-25 00:29:28.763284 | 
2025-12-25 00:29:28.763390 | TASK [ansible-test : Run the test suite]
2025-12-25 00:29:29.508662 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2025-12-25 00:29:29.580963 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2025-12-25 00:29:31.617600 | controller | 2.9.0: Pulling from ansible/default-test-container
2025-12-25 00:29:32.533185 | controller | 171857c49d0f: Pulling fs layer
2025-12-25 00:29:32.533236 | controller | 419640447d26: Pulling fs layer
2025-12-25 00:29:32.533242 | controller | 61e52f862619: Pulling fs layer
2025-12-25 00:29:32.533247 | controller | 851de0426e26: Pulling fs layer
2025-12-25 00:29:32.533560 | controller | 37425716ea5b: Pulling fs layer
2025-12-25 00:29:32.533578 | controller | cd9b0c439616: Pulling fs layer
2025-12-25 00:29:32.533588 | controller | d4612dbd8855: Pulling fs layer
2025-12-25 00:29:32.533592 | controller | 554699364730: Pulling fs layer
2025-12-25 00:29:32.533597 | controller | 1ff15d4bdb3e: Pulling fs layer
2025-12-25 00:29:32.533601 | controller | 5f2e4724adea: Pulling fs layer
2025-12-25 00:29:32.533605 | controller | a02d9a95ccb8: Pulling fs layer
2025-12-25 00:29:32.533608 | controller | 989f4ce370cb: Pulling fs layer
2025-12-25 00:29:32.533613 | controller | 0d7f52cdf9df: Pulling fs layer
2025-12-25 00:29:32.533632 | controller | c423742351d5: Pulling fs layer
2025-12-25 00:29:32.533637 | controller | 109236341d21: Pulling fs layer
2025-12-25 00:29:32.533641 | controller | 07a2f61c96b8: Pulling fs layer
2025-12-25 00:29:32.533645 | controller | 041f122f27ae: Pulling fs layer
2025-12-25 00:29:32.533649 | controller | ca6f5098b12e: Pulling fs layer
2025-12-25 00:29:32.533653 | controller | 1eb3e7b2afd9: Pulling fs layer
2025-12-25 00:29:32.533657 | controller | 18a0b750727a: Pulling fs layer
2025-12-25 00:29:32.533661 | controller | 17f2f596716c: Pulling fs layer
2025-12-25 00:29:32.533667 | controller | f35a9998a9bd: Pulling fs layer
2025-12-25 00:29:32.533671 | controller | fdfc10566405: Pulling fs layer
2025-12-25 00:29:32.533675 | controller | 1ff1c442c5fe: Pulling fs layer
2025-12-25 00:29:32.533680 | controller | a3443c4a17ed: Pulling fs layer
2025-12-25 00:29:32.533684 | controller | 0edcf9fb2b15: Pulling fs layer
2025-12-25 00:29:32.533688 | controller | 5af43b69e845: Pulling fs layer
2025-12-25 00:29:32.533692 | controller | 1651773effb2: Pulling fs layer
2025-12-25 00:29:32.533696 | controller | 79e23c5c0fe4: Pulling fs layer
2025-12-25 00:29:32.533704 | controller | e5058b1faaa6: Pulling fs layer
2025-12-25 00:29:32.533708 | controller | 846775490569: Pulling fs layer
2025-12-25 00:29:32.533712 | controller | 851de0426e26: Waiting
2025-12-25 00:29:32.533716 | controller | 37425716ea5b: Waiting
2025-12-25 00:29:32.533720 | controller | cd9b0c439616: Waiting
2025-12-25 00:29:32.533724 | controller | d4612dbd8855: Waiting
2025-12-25 00:29:32.533727 | controller | 554699364730: Waiting
2025-12-25 00:29:32.533731 | controller | 1ff15d4bdb3e: Waiting
2025-12-25 00:29:32.533738 | controller | 5f2e4724adea: Waiting
2025-12-25 00:29:32.533742 | controller | a02d9a95ccb8: Waiting
2025-12-25 00:29:32.533745 | controller | 989f4ce370cb: Waiting
2025-12-25 00:29:32.533749 | controller | 0d7f52cdf9df: Waiting
2025-12-25 00:29:32.533753 | controller | c423742351d5: Waiting
2025-12-25 00:29:32.533757 | controller | 109236341d21: Waiting
2025-12-25 00:29:32.533761 | controller | 07a2f61c96b8: Waiting
2025-12-25 00:29:32.533765 | controller | 041f122f27ae: Waiting
2025-12-25 00:29:32.533769 | controller | ca6f5098b12e: Waiting
2025-12-25 00:29:32.533773 | controller | 1eb3e7b2afd9: Waiting
2025-12-25 00:29:32.533777 | controller | 18a0b750727a: Waiting
2025-12-25 00:29:32.533781 | controller | 17f2f596716c: Waiting
2025-12-25 00:29:32.533787 | controller | f35a9998a9bd: Waiting
2025-12-25 00:29:32.533791 | controller | fdfc10566405: Waiting
2025-12-25 00:29:32.533795 | controller | 1ff1c442c5fe: Waiting
2025-12-25 00:29:32.533799 | controller | a3443c4a17ed: Waiting
2025-12-25 00:29:32.533803 | controller | 0edcf9fb2b15: Waiting
2025-12-25 00:29:32.533807 | controller | 5af43b69e845: Waiting
2025-12-25 00:29:32.533811 | controller | 1651773effb2: Waiting
2025-12-25 00:29:32.533815 | controller | 79e23c5c0fe4: Waiting
2025-12-25 00:29:32.533819 | controller | e5058b1faaa6: Waiting
2025-12-25 00:29:32.533823 | controller | 846775490569: Waiting
2025-12-25 00:29:32.533832 | controller | 419640447d26: Verifying Checksum
2025-12-25 00:29:32.644400 | controller | 419640447d26: Download complete
2025-12-25 00:29:32.644446 | controller | 61e52f862619: Verifying Checksum
2025-12-25 00:29:32.851908 | controller | 61e52f862619: Download complete
2025-12-25 00:29:32.851949 | controller | 171857c49d0f: Verifying Checksum
2025-12-25 00:29:33.573896 | controller | 171857c49d0f: Download complete
2025-12-25 00:29:33.573936 | controller | 851de0426e26: Verifying Checksum
2025-12-25 00:29:33.983975 | controller | 851de0426e26: Download complete
2025-12-25 00:29:33.984018 | controller | cd9b0c439616: Verifying Checksum
2025-12-25 00:29:34.539947 | controller | cd9b0c439616: Download complete
2025-12-25 00:29:34.539992 | controller | d4612dbd8855: Verifying Checksum
2025-12-25 00:29:34.792565 | controller | d4612dbd8855: Download complete
2025-12-25 00:29:34.792607 | controller | 171857c49d0f: Pull complete
2025-12-25 00:29:34.815424 | controller | 419640447d26: Pull complete
2025-12-25 00:29:34.834149 | controller | 61e52f862619: Pull complete
2025-12-25 00:29:34.865767 | controller | 851de0426e26: Pull complete
2025-12-25 00:29:35.858024 | controller | 1ff15d4bdb3e: Verifying Checksum
2025-12-25 00:29:36.044034 | controller | 1ff15d4bdb3e: Download complete
2025-12-25 00:29:36.044071 | controller | 554699364730: Verifying Checksum
2025-12-25 00:29:37.014337 | controller | 554699364730: Download complete
2025-12-25 00:29:37.014382 | controller | a02d9a95ccb8: Verifying Checksum
2025-12-25 00:29:37.072584 | controller | a02d9a95ccb8: Download complete
2025-12-25 00:29:37.072602 | controller | 5f2e4724adea: Verifying Checksum
2025-12-25 00:29:37.948746 | controller | 5f2e4724adea: Download complete
2025-12-25 00:29:37.948792 | controller | 989f4ce370cb: Verifying Checksum
2025-12-25 00:29:38.319277 | controller | 989f4ce370cb: Download complete
2025-12-25 00:29:38.319342 | controller | 0d7f52cdf9df: Verifying Checksum
2025-12-25 00:29:38.841079 | controller | 0d7f52cdf9df: Download complete
2025-12-25 00:29:38.841131 | controller | c423742351d5: Verifying Checksum
2025-12-25 00:29:38.841996 | controller | c423742351d5: Download complete
2025-12-25 00:29:39.700526 | controller | 07a2f61c96b8: Verifying Checksum
2025-12-25 00:29:39.700609 | controller | 07a2f61c96b8: Download complete
2025-12-25 00:29:39.791272 | controller | 109236341d21: Verifying Checksum
2025-12-25 00:29:39.791346 | controller | 109236341d21: Download complete
2025-12-25 00:29:40.669811 | controller | 041f122f27ae: Verifying Checksum
2025-12-25 00:29:40.669942 | controller | 041f122f27ae: Download complete
2025-12-25 00:29:40.868460 | controller | ca6f5098b12e: Verifying Checksum
2025-12-25 00:29:40.868487 | controller | ca6f5098b12e: Download complete
2025-12-25 00:29:41.705213 | controller | 1eb3e7b2afd9: Verifying Checksum
2025-12-25 00:29:41.705268 | controller | 1eb3e7b2afd9: Download complete
2025-12-25 00:29:41.848570 | controller | 18a0b750727a: Verifying Checksum
2025-12-25 00:29:42.833655 | controller | 18a0b750727a: Download complete
2025-12-25 00:29:42.833735 | controller | f35a9998a9bd: Download complete
2025-12-25 00:29:42.839816 | controller | 17f2f596716c: Verifying Checksum
2025-12-25 00:29:42.840014 | controller | 17f2f596716c: Download complete
2025-12-25 00:29:43.791551 | controller | fdfc10566405: Download complete
2025-12-25 00:29:43.929492 | controller | 1ff1c442c5fe: Verifying Checksum
2025-12-25 00:29:43.929641 | controller | 1ff1c442c5fe: Download complete
2025-12-25 00:29:45.021579 | controller | a3443c4a17ed: Verifying Checksum
2025-12-25 00:29:45.021636 | controller | a3443c4a17ed: Download complete
2025-12-25 00:29:45.133257 | controller | 0edcf9fb2b15: Verifying Checksum
2025-12-25 00:29:45.133365 | controller | 0edcf9fb2b15: Download complete
2025-12-25 00:29:46.202932 | controller | 5af43b69e845: Verifying Checksum
2025-12-25 00:29:46.202997 | controller | 5af43b69e845: Download complete
2025-12-25 00:29:46.271988 | controller | 1651773effb2: Verifying Checksum
2025-12-25 00:29:46.272107 | controller | 1651773effb2: Download complete
2025-12-25 00:29:47.198244 | controller | 79e23c5c0fe4: Verifying Checksum
2025-12-25 00:29:47.198311 | controller | 79e23c5c0fe4: Download complete
2025-12-25 00:29:47.711446 | controller | e5058b1faaa6: Verifying Checksum
2025-12-25 00:29:47.711636 | controller | e5058b1faaa6: Download complete
2025-12-25 00:29:49.583369 | controller | 846775490569: Verifying Checksum
2025-12-25 00:29:51.685502 | controller | 846775490569: Download complete
2025-12-25 00:29:51.685599 | controller | 37425716ea5b: Verifying Checksum
2025-12-25 00:29:51.685681 | controller | 37425716ea5b: Download complete
2025-12-25 00:30:02.881394 | controller | 37425716ea5b: Pull complete
2025-12-25 00:30:02.903519 | controller | cd9b0c439616: Pull complete
2025-12-25 00:30:02.926621 | controller | d4612dbd8855: Pull complete
2025-12-25 00:30:05.572823 | controller | 554699364730: Pull complete
2025-12-25 00:30:05.602899 | controller | 1ff15d4bdb3e: Pull complete
2025-12-25 00:30:05.624591 | controller | 5f2e4724adea: Pull complete
2025-12-25 00:30:05.654229 | controller | a02d9a95ccb8: Pull complete
2025-12-25 00:30:05.676041 | controller | 989f4ce370cb: Pull complete
2025-12-25 00:30:05.696822 | controller | 0d7f52cdf9df: Pull complete
2025-12-25 00:30:05.717869 | controller | c423742351d5: Pull complete
2025-12-25 00:30:07.085613 | controller | 109236341d21: Pull complete
2025-12-25 00:30:07.108992 | controller | 07a2f61c96b8: Pull complete
2025-12-25 00:30:07.132087 | controller | 041f122f27ae: Pull complete
2025-12-25 00:30:07.230155 | controller | ca6f5098b12e: Pull complete
2025-12-25 00:30:07.276450 | controller | 1eb3e7b2afd9: Pull complete
2025-12-25 00:30:07.318083 | controller | 18a0b750727a: Pull complete
2025-12-25 00:30:07.336119 | controller | 17f2f596716c: Pull complete
2025-12-25 00:30:07.355379 | controller | f35a9998a9bd: Pull complete
2025-12-25 00:30:07.380302 | controller | fdfc10566405: Pull complete
2025-12-25 00:30:07.403864 | controller | 1ff1c442c5fe: Pull complete
2025-12-25 00:30:08.344467 | controller | a3443c4a17ed: Pull complete
2025-12-25 00:30:09.664517 | controller | 0edcf9fb2b15: Pull complete
2025-12-25 00:30:11.306341 | controller | 5af43b69e845: Pull complete
2025-12-25 00:30:12.889330 | controller | 1651773effb2: Pull complete
2025-12-25 00:30:14.590546 | controller | 79e23c5c0fe4: Pull complete
2025-12-25 00:30:16.474488 | controller | e5058b1faaa6: Pull complete
2025-12-25 00:30:17.994466 | controller | 846775490569: Pull complete
2025-12-25 00:30:18.027428 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2025-12-25 00:30:18.034425 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2025-12-25 00:30:18.041243 | controller | quay.io/ansible/default-test-container:2.9.0
2025-12-25 00:30:18.043673 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2025-12-25 00:30:18.061221 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2025-12-25 00:30:19.744542 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2025-12-25 00:30:19.744665 | controller | 04a5f4cda3ee: Pulling fs layer
2025-12-25 00:30:19.744747 | controller | ff496a88c8ed: Pulling fs layer
2025-12-25 00:30:19.744826 | controller | 0ce83f459fe7: Pulling fs layer
2025-12-25 00:30:19.745528 | controller | 2e5170e1f099: Pulling fs layer
2025-12-25 00:30:20.813326 | controller | 7641eb41b08c: Pulling fs layer
2025-12-25 00:30:20.813389 | controller | ad15fa9da398: Pulling fs layer
2025-12-25 00:30:20.813400 | controller | 087d91352424: Pulling fs layer
2025-12-25 00:30:20.813409 | controller | 8b92efd6a100: Pulling fs layer
2025-12-25 00:30:20.813418 | controller | 2e5170e1f099: Waiting
2025-12-25 00:30:20.813427 | controller | 7641eb41b08c: Waiting
2025-12-25 00:30:20.813449 | controller | ad15fa9da398: Waiting
2025-12-25 00:30:20.813458 | controller | 087d91352424: Waiting
2025-12-25 00:30:20.813475 | controller | 8b92efd6a100: Waiting
2025-12-25 00:30:20.813516 | controller | 0ce83f459fe7: Verifying Checksum
2025-12-25 00:30:20.813674 | controller | 0ce83f459fe7: Download complete
2025-12-25 00:30:21.090638 | controller | ff496a88c8ed: Verifying Checksum
2025-12-25 00:30:21.090704 | controller | ff496a88c8ed: Download complete
2025-12-25 00:30:21.387321 | controller | 04a5f4cda3ee: Verifying Checksum
2025-12-25 00:30:21.388556 | controller | 04a5f4cda3ee: Download complete
2025-12-25 00:30:22.256396 | controller | 2e5170e1f099: Verifying Checksum
2025-12-25 00:30:22.602663 | controller | 2e5170e1f099: Download complete
2025-12-25 00:30:22.602744 | controller | 7641eb41b08c: Verifying Checksum
2025-12-25 00:30:22.621063 | controller | 7641eb41b08c: Download complete
2025-12-25 00:30:22.621103 | controller | 04a5f4cda3ee: Pull complete
2025-12-25 00:30:22.646454 | controller | ff496a88c8ed: Pull complete
2025-12-25 00:30:22.680798 | controller | ad15fa9da398: Verifying Checksum
2025-12-25 00:30:22.691927 | controller | ad15fa9da398: Download complete
2025-12-25 00:30:22.692001 | controller | 0ce83f459fe7: Pull complete
2025-12-25 00:30:23.610170 | controller | 2e5170e1f099: Pull complete
2025-12-25 00:30:23.651248 | controller | 7641eb41b08c: Pull complete
2025-12-25 00:30:23.672644 | controller | ad15fa9da398: Pull complete
2025-12-25 00:30:23.806254 | controller | 8b92efd6a100: Verifying Checksum
2025-12-25 00:30:23.947056 | controller | 8b92efd6a100: Download complete
2025-12-25 00:30:23.947117 | controller | 087d91352424: Verifying Checksum
2025-12-25 00:30:24.528521 | controller | 087d91352424: Download complete
2025-12-25 00:30:24.528614 | controller | 087d91352424: Pull complete
2025-12-25 00:30:24.559243 | controller | 8b92efd6a100: Pull complete
2025-12-25 00:30:24.579487 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2025-12-25 00:30:24.583495 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2025-12-25 00:30:24.590614 | controller | quay.io/ansible/pypi-test-container:1.0.0
2025-12-25 00:30:24.593967 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2025-12-25 00:30:33.525208 | controller | Run command: docker inspect 1cb3928bac8a90f6f5a410702b8fa2c522af324059e39383f079ae46b775c912
2025-12-25 00:30:33.575534 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2025-12-25 00:30:33.587268 | controller | Including collection: ansible.netcommon (292 files)
2025-12-25 00:30:33.594419 | controller | Including collection: ansible.network (23 files)
2025-12-25 00:30:33.594437 | controller | Including collection: ansible.utils (442 files)
2025-12-25 00:30:33.619045 | controller | Including collection: arista.eos (1022 files)
2025-12-25 00:30:33.650342 | controller | Including collection: cisco.ios (1174 files)
2025-12-25 00:30:33.679617 | controller | Including collection: cisco.iosxr (1044 files)
2025-12-25 00:30:33.729925 | controller | Including collection: cisco.nxos (1682 files)
2025-12-25 00:30:33.755412 | controller | Including collection: junipernetworks.junos (963 files)
2025-12-25 00:30:33.779166 | controller | Creating a payload archive containing 7368 files...
2025-12-25 00:30:35.279163 | controller | Created a 5992237 byte payload archive containing 7368 files in 1 seconds.
2025-12-25 00:30:35.280348 | controller | Assuming Docker is available on localhost.
2025-12-25 00:30:35.280526 | 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-25 00:30:35.692403 | controller | Run command: docker exec -i fe5046f579f4a1eaed554aad264cbd024b4711c70cef5a041f1ed445a7822fdd dd of=/root/docker.sh bs=65536
2025-12-25 00:30:35.750569 | controller | Run command: docker exec fe5046f579f4a1eaed554aad264cbd024b4711c70cef5a041f1ed445a7822fdd /bin/bash /root/docker.sh
2025-12-25 00:30:35.808004 | controller | Run command: docker exec -i fe5046f579f4a1eaed554aad264cbd024b4711c70cef5a041f1ed445a7822fdd dd of=/root/test.tgz bs=65536
2025-12-25 00:30:35.873964 | controller | Run command: docker exec fe5046f579f4a1eaed554aad264cbd024b4711c70cef5a041f1ed445a7822fdd tar oxzf /root/test.tgz -C /root
2025-12-25 00:30:36.488040 | controller | Run command: docker exec fe5046f579f4a1eaed554aad264cbd024b4711c70cef5a041f1ed445a7822fdd /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-4yky95g5.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2025-12-25 00:30:36.745724 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2025-12-25 00:30:36.745945 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2025-12-25 00:30:36.746259 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2025-12-25 00:30:36.747684 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2025-12-25 00:30:36.748164 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2025-12-25 00:30:36.752855 | controller | No tests applicable.
2025-12-25 00:30:36.753068 | controller | Running sanity test 'ansible-doc' with Python 3.6
2025-12-25 00:30:36.753093 | controller | No tests applicable.
2025-12-25 00:30:36.753561 | controller | Running sanity test 'changelog' with Python 3.6
2025-12-25 00:30:36.753587 | 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-25 00:30:37.337103 | 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-25 00:30:37.716623 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2025-12-25 00:30:37.756449 | 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-25 00:30:37.757075 | 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-25 00:30:37.790421 | 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-25 00:30:37.790734 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2025-12-25 00:30:37.790905 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2025-12-25 00:30:37.791091 | controller | Removing custom PyPI hosts entries: /etc/hosts
2025-12-25 00:30:37.809289 | controller | Run command: docker exec fe5046f579f4a1eaed554aad264cbd024b4711c70cef5a041f1ed445a7822fdd tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2025-12-25 00:30:37.856965 | controller | Run command: docker exec -i fe5046f579f4a1eaed554aad264cbd024b4711c70cef5a041f1ed445a7822fdd dd if=/root/output.tgz bs=65536
2025-12-25 00:30:37.904573 | controller | Run command: tar oxzf /tmp/ansible-result-cfh8v6ae.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2025-12-25 00:30:37.914709 | controller | Run command: docker rm -f 1cb3928bac8a90f6f5a410702b8fa2c522af324059e39383f079ae46b775c912
2025-12-25 00:30:38.634296 | controller | Run command: docker rm -f fe5046f579f4a1eaed554aad264cbd024b4711c70cef5a041f1ed445a7822fdd
2025-12-25 00:30:39.027734 | controller | ERROR: Command "docker exec fe5046f579f4a1eaed554aad264cbd024b4711c70cef5a041f1ed445a7822fdd /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-4yky95g5.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2025-12-25 00:30:39.356767 | controller | ERROR
2025-12-25 00:30:39.356928 | controller | {
2025-12-25 00:30:39.356967 | controller |   "delta": "0:01:09.903889",
2025-12-25 00:30:39.357030 | controller |   "end": "2025-12-25 00:30:39.045666",
2025-12-25 00:30:39.357068 | controller |   "msg": "non-zero return code",
2025-12-25 00:30:39.357095 | controller |   "rc": 1,
2025-12-25 00:30:39.357120 | controller |   "start": "2025-12-25 00:29:29.141777"
2025-12-25 00:30:39.357145 | controller | }
failure
2025-12-25 00:30:39.362640 | 
2025-12-25 00:30:39.362723 | PLAY RECAP
2025-12-25 00:30:39.362783 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2025-12-25 00:30:39.362816 | 
2025-12-25 00:30:39.493859 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-12-25 00:30:39.495003 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-25 00:30:40.096125 | 
2025-12-25 00:30:40.096391 | PLAY [all]
2025-12-25 00:30:40.118421 | 
2025-12-25 00:30:40.118566 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-12-25 00:30:40.558624 | controller | changed: non-zero return code
2025-12-25 00:30:40.564822 | 
2025-12-25 00:30:40.564948 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-12-25 00:30:40.589439 | controller | skipping: Conditional result was False
2025-12-25 00:30:40.595798 | 
2025-12-25 00:30:40.595891 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-12-25 00:30:40.626727 | 
2025-12-25 00:30:40.626894 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-12-25 00:30:40.664694 | 
2025-12-25 00:30:40.664977 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-12-25 00:30:40.682446 | controller | skipping: Conditional result was False
2025-12-25 00:30:40.694322 | 
2025-12-25 00:30:40.694468 | LOOP [fetch-subunit-output : Generate subunit file]
2025-12-25 00:30:40.728407 | 
2025-12-25 00:30:40.728613 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-12-25 00:30:40.744468 | controller | skipping: Conditional result was False
2025-12-25 00:30:40.754223 | 
2025-12-25 00:30:40.754339 | TASK [fetch-subunit-output : Remove the temporary file]
2025-12-25 00:30:40.769267 | controller | skipping: Conditional result was False
2025-12-25 00:30:40.780903 | 
2025-12-25 00:30:40.781073 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-12-25 00:30:40.796277 | controller | skipping: Conditional result was False
2025-12-25 00:30:40.828036 | 
2025-12-25 00:30:40.828182 | PLAY RECAP
2025-12-25 00:30:40.828230 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-25 00:30:40.828252 | 
2025-12-25 00:30:40.937548 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-25 00:30:40.938692 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-12-25 00:30:41.535412 | 
2025-12-25 00:30:41.535543 | PLAY [all:!appliance*]
2025-12-25 00:30:41.560219 | 
2025-12-25 00:30:41.560361 | TASK [unregister the node]
2025-12-25 00:30:42.098754 | controller | skipping: Conditional result was False
2025-12-25 00:30:42.112374 | 
2025-12-25 00:30:42.112614 | TASK [include_role : fetch-output]
2025-12-25 00:30:42.166976 | controller | ok
2025-12-25 00:30:42.198888 | 
2025-12-25 00:30:42.199073 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-25 00:30:42.275461 | controller | skipping: Conditional result was False
2025-12-25 00:30:42.288420 | 
2025-12-25 00:30:42.288602 | TASK [fetch-output : Set log path for single node]
2025-12-25 00:30:42.334673 | controller | ok
2025-12-25 00:30:42.348156 | 
2025-12-25 00:30:42.348299 | LOOP [fetch-output : Ensure local output dirs]
2025-12-25 00:30:42.758499 | controller -> localhost | ok: "/var/lib/zuul/builds/5eab4088b67d47e9bfbd747b1bee96b0/work/logs"
2025-12-25 00:30:42.981482 | controller -> localhost | changed: "/var/lib/zuul/builds/5eab4088b67d47e9bfbd747b1bee96b0/work/artifacts"
2025-12-25 00:30:43.207358 | controller -> localhost | changed: "/var/lib/zuul/builds/5eab4088b67d47e9bfbd747b1bee96b0/work/docs"
2025-12-25 00:30:43.230199 | 
2025-12-25 00:30:43.230361 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-25 00:30:43.946664 | controller | changed:
2025-12-25 00:30:43.946855 | controller | .d..t...... ./
2025-12-25 00:30:43.946884 | controller | cd+++++++++ controller/
2025-12-25 00:30:43.946919 | controller | changed: All items complete
2025-12-25 00:30:43.946940 | 
2025-12-25 00:30:44.489933 | controller | changed: .d..t...... ./
2025-12-25 00:30:45.061452 | controller | changed: .d..t...... ./
2025-12-25 00:30:45.084076 | 
2025-12-25 00:30:45.084221 | TASK [include_role : fetch-output-openshift]
2025-12-25 00:30:45.108547 | controller | skipping: Conditional result was False
2025-12-25 00:30:45.116147 | 
2025-12-25 00:30:45.116277 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-25 00:30:45.154462 | controller | skipping: Conditional result was False
2025-12-25 00:30:45.167414 | controller | skipping: Conditional result was False
2025-12-25 00:30:45.216548 | 
2025-12-25 00:30:45.216720 | PLAY [localhost]
2025-12-25 00:30:45.237519 | 
2025-12-25 00:30:45.237602 | TASK [Run Zuul manifest role]
2025-12-25 00:30:45.255140 | localhost | ok
2025-12-25 00:30:45.269219 | 
2025-12-25 00:30:45.269308 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-25 00:30:45.655314 | localhost | changed
2025-12-25 00:30:45.660987 | 
2025-12-25 00:30:45.661093 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-25 00:30:45.692292 | localhost | ok
2025-12-25 00:30:45.702894 | 
2025-12-25 00:30:45.703004 | TASK [Set zuul-log-path fact]
2025-12-25 00:30:45.724190 | localhost | ok
2025-12-25 00:30:45.740590 | 
2025-12-25 00:30:45.740690 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-25 00:30:45.780925 | localhost | ok
2025-12-25 00:30:45.790283 | 
2025-12-25 00:30:45.790404 | LOOP [Run upload-logs-swift role]
2025-12-25 00:30:45.814882 | localhost | Output suppressed because no_log was given
2025-12-25 00:30:45.844169 | 
2025-12-25 00:30:45.844294 | TASK [Set zuul-log-path fact]
2025-12-25 00:30:45.878873 | localhost | skipping: Conditional result was False
2025-12-25 00:30:45.885306 | 
2025-12-25 00:30:45.885408 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-12-25 00:30:46.337558 | localhost -> localhost | ok: Runtime: 0:00:00.010521
2025-12-25 00:30:46.344071 | 
2025-12-25 00:30:46.344148 | TASK [upload-logs-swift : Upload logs to swift]