2025-11-08 00:07:10.257897 | Job console starting...
2025-11-08 00:07:10.269406 | Updating repositories
2025-11-08 00:07:10.468326 | Preparing job workspace
2025-11-08 00:07:18.376717 | Running Ansible setup...
2025-11-08 00:07:26.162694 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-08 00:07:26.785204 | 
2025-11-08 00:07:26.785328 | PLAY [localhost]
2025-11-08 00:07:26.793695 | 
2025-11-08 00:07:26.793778 | TASK [Gathering Facts]
2025-11-08 00:07:27.897084 | localhost | ok
2025-11-08 00:07:27.924959 | 
2025-11-08 00:07:27.925173 | TASK [Setup log path fact]
2025-11-08 00:07:27.946276 | localhost | ok
2025-11-08 00:07:27.964499 | 
2025-11-08 00:07:27.964604 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-08 00:07:28.006511 | localhost | ok
2025-11-08 00:07:28.018833 | 
2025-11-08 00:07:28.018930 | TASK [emit-job-header : Print job information]
2025-11-08 00:07:28.083457 | # Job Information
2025-11-08 00:07:28.083644 | Ansible Version: 2.15.12
2025-11-08 00:07:28.083691 | Job: ansible-test-sanity-docker-stable-2.10
2025-11-08 00:07:28.083723 | Pipeline: periodic
2025-11-08 00:07:28.083758 | Executor: ze01.softwarefactory-project.io
2025-11-08 00:07:28.083792 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-11-08 00:07:28.083825 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/959/ansible/9599ce379eae46d0b45cdb1b3c8b2d43/
2025-11-08 00:07:28.083856 | Event ID: 9db656c0baa84bdc9b327314cc4269ab
2025-11-08 00:07:28.091703 | 
2025-11-08 00:07:28.091875 | LOOP [emit-job-header : Print node information]
2025-11-08 00:07:28.211250 | localhost | ok:
2025-11-08 00:07:28.211462 | localhost | # Node Information
2025-11-08 00:07:28.211492 | localhost | Inventory Hostname: controller
2025-11-08 00:07:28.211513 | localhost | Hostname: np0005515347
2025-11-08 00:07:28.211533 | localhost | Username: zuul
2025-11-08 00:07:28.211559 | localhost | Distro: Fedora 37
2025-11-08 00:07:28.211579 | localhost | Provider: ansible-vexxhost-ams1
2025-11-08 00:07:28.211598 | localhost | Region: ams1
2025-11-08 00:07:28.211616 | localhost | Label: ansible-fedora-37-1vcpu
2025-11-08 00:07:28.211634 | localhost | Product Name: OpenStack Nova
2025-11-08 00:07:28.211652 | localhost | Interface IP: 38.129.16.85
2025-11-08 00:07:28.230990 | 
2025-11-08 00:07:28.231123 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-08 00:07:28.715676 | localhost -> localhost | changed
2025-11-08 00:07:28.721243 | 
2025-11-08 00:07:28.721322 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-08 00:07:29.777301 | localhost -> localhost | changed
2025-11-08 00:07:29.797851 | 
2025-11-08 00:07:29.797924 | PLAY [all:!appliance*]
2025-11-08 00:07:29.815665 | 
2025-11-08 00:07:29.815769 | TASK [include_role : start-zuul-console]
2025-11-08 00:07:29.836733 | controller | ok
2025-11-08 00:07:29.850394 | 
2025-11-08 00:07:29.850490 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-08 00:07:30.944696 | controller | ok
2025-11-08 00:07:30.970726 | 
2025-11-08 00:07:30.970901 | TASK [use-our-mirror : Retrieve the IP address]
2025-11-08 00:07:33.199338 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-11-08 00:07:33.216921 | 
2025-11-08 00:07:33.217128 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-11-08 00:07:33.595942 | controller | skipping: Conditional result was False
2025-11-08 00:07:33.602937 | 
2025-11-08 00:07:33.603051 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-11-08 00:07:33.626900 | controller | skipping: Conditional result was False
2025-11-08 00:07:33.636574 | 
2025-11-08 00:07:33.636677 | TASK [use-our-mirror : Create the podman configuration directory]
2025-11-08 00:07:33.662112 | controller | skipping: Conditional result was False
2025-11-08 00:07:33.670636 | 
2025-11-08 00:07:33.670823 | TASK [use-our-mirror : Copy the podman configuration]
2025-11-08 00:07:33.697543 | controller | skipping: Conditional result was False
2025-11-08 00:07:33.705396 | 
2025-11-08 00:07:33.705539 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-11-08 00:07:33.729679 | controller | skipping: Conditional result was False
2025-11-08 00:07:33.736497 | 
2025-11-08 00:07:33.736603 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-11-08 00:07:33.752317 | controller | skipping: Conditional result was False
2025-11-08 00:07:33.764215 | 
2025-11-08 00:07:33.764325 | TASK [Disable Fedora Modular]
2025-11-08 00:07:35.001884 | controller | changed
2025-11-08 00:07:35.013955 | 
2025-11-08 00:07:35.014116 | TASK [Enable EPEL]
2025-11-08 00:07:35.041429 | controller | skipping: Conditional result was False
2025-11-08 00:07:35.054904 | 
2025-11-08 00:07:35.055078 | TASK [Register the RHEL node]
2025-11-08 00:07:35.474198 | 
2025-11-08 00:07:35.474481 | TASK [Show the subscription-manager status]
2025-11-08 00:07:35.875181 | controller | skipping: Conditional result was False
2025-11-08 00:07:35.889126 | 
2025-11-08 00:07:35.889279 | TASK [Enable EPEL on RHEL]
2025-11-08 00:07:36.287663 | controller | skipping: Conditional result was False
2025-11-08 00:07:36.297325 | 
2025-11-08 00:07:36.297457 | TASK [Install git and tox]
2025-11-08 00:09:45.010839 | controller | changed
2025-11-08 00:09:45.026105 | 
2025-11-08 00:09:45.026281 | TASK [include_role : prepare-workspace]
2025-11-08 00:09:45.066686 | controller | ok
2025-11-08 00:09:45.104172 | 
2025-11-08 00:09:45.104292 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-08 00:09:45.940040 | controller | ok
2025-11-08 00:09:45.954568 | 
2025-11-08 00:09:45.954709 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-08 00:10:01.513761 | controller | Output suppressed because no_log was given
2025-11-08 00:10:01.524743 | 
2025-11-08 00:10:01.524829 | TASK [include_role : prepare-workspace-openshift]
2025-11-08 00:10:01.548957 | controller | skipping: Conditional result was False
2025-11-08 00:10:01.614000 | 
2025-11-08 00:10:01.614117 | PLAY [all:!appliance]
2025-11-08 00:10:01.629711 | 
2025-11-08 00:10:01.629809 | TASK [Run add-build-sshkey role (RSA)]
2025-11-08 00:10:01.648886 | controller | ok
2025-11-08 00:10:01.663858 | 
2025-11-08 00:10:01.663924 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-08 00:10:01.890669 | controller -> localhost | ok
2025-11-08 00:10:01.897544 | 
2025-11-08 00:10:01.897632 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-08 00:10:01.937426 | controller | ok
2025-11-08 00:10:01.951574 | controller | included: /var/lib/zuul/builds/9599ce379eae46d0b45cdb1b3c8b2d43/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-08 00:10:01.958033 | 
2025-11-08 00:10:01.958098 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-08 00:10:02.469676 | controller -> localhost | Generating public/private rsa key pair.
2025-11-08 00:10:02.470065 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9599ce379eae46d0b45cdb1b3c8b2d43/work/9599ce379eae46d0b45cdb1b3c8b2d43_id_rsa.
2025-11-08 00:10:02.470134 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9599ce379eae46d0b45cdb1b3c8b2d43/work/9599ce379eae46d0b45cdb1b3c8b2d43_id_rsa.pub.
2025-11-08 00:10:02.470181 | controller -> localhost | The key fingerprint is:
2025-11-08 00:10:02.470224 | controller -> localhost | SHA256:p7606NhDMjyzCz1c2MReFs/nf77e68DvWH9Ks6bF4yM zuul-build-sshkey
2025-11-08 00:10:02.470265 | controller -> localhost | The key's randomart image is:
2025-11-08 00:10:02.470305 | controller -> localhost | +---[RSA 2048]----+
2025-11-08 00:10:02.470344 | controller -> localhost | |        .        |
2025-11-08 00:10:02.470385 | controller -> localhost | |     .   +       |
2025-11-08 00:10:02.470423 | controller -> localhost | |      o o o .    |
2025-11-08 00:10:02.470462 | controller -> localhost | |     = o   o     |
2025-11-08 00:10:02.470499 | controller -> localhost | |   .. + S . .    |
2025-11-08 00:10:02.470554 | controller -> localhost | |   o*..  o  .o   |
2025-11-08 00:10:02.470604 | controller -> localhost | |  . +B  o    oB..|
2025-11-08 00:10:02.470648 | controller -> localhost | |   ..+.+ .  E+B*+|
2025-11-08 00:10:02.470688 | controller -> localhost | |    oo+.+.  .=BXB|
2025-11-08 00:10:02.470732 | controller -> localhost | +----[SHA256]-----+
2025-11-08 00:10:02.470835 | controller -> localhost | ok: Runtime: 0:00:00.108409
2025-11-08 00:10:02.484939 | 
2025-11-08 00:10:02.485135 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-08 00:10:02.535121 | controller | ok
2025-11-08 00:10:02.557322 | controller | included: /var/lib/zuul/builds/9599ce379eae46d0b45cdb1b3c8b2d43/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-08 00:10:02.569960 | 
2025-11-08 00:10:02.570203 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-08 00:10:02.595900 | controller | skipping: Conditional result was False
2025-11-08 00:10:02.605701 | 
2025-11-08 00:10:02.605794 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-08 00:10:03.725823 | controller | changed
2025-11-08 00:10:03.734900 | 
2025-11-08 00:10:03.734999 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-08 00:10:04.467767 | controller | ok
2025-11-08 00:10:04.481159 | 
2025-11-08 00:10:04.481310 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-08 00:10:07.658555 | controller | changed
2025-11-08 00:10:07.671641 | 
2025-11-08 00:10:07.671787 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-08 00:10:10.770125 | controller | changed
2025-11-08 00:10:10.778909 | 
2025-11-08 00:10:10.779011 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-08 00:10:10.805455 | controller | skipping: Conditional result was False
2025-11-08 00:10:10.833905 | 
2025-11-08 00:10:10.834081 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-08 00:10:11.241355 | controller -> localhost | changed
2025-11-08 00:10:11.261208 | 
2025-11-08 00:10:11.261582 | TASK [add-build-sshkey : Add back temp key]
2025-11-08 00:10:11.531759 | controller -> localhost | Identity added: /var/lib/zuul/builds/9599ce379eae46d0b45cdb1b3c8b2d43/work/9599ce379eae46d0b45cdb1b3c8b2d43_id_rsa (zuul-build-sshkey)
2025-11-08 00:10:11.532041 | controller -> localhost | ok: Runtime: 0:00:00.007218
2025-11-08 00:10:11.539772 | 
2025-11-08 00:10:11.539845 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-08 00:10:12.491082 | controller | ok
2025-11-08 00:10:12.497627 | 
2025-11-08 00:10:12.497712 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-08 00:10:12.522735 | controller | skipping: Conditional result was False
2025-11-08 00:10:12.535984 | 
2025-11-08 00:10:12.536090 | TASK [Run add-build-sshkey role (ECDSA)]
2025-11-08 00:10:12.566350 | controller | ok
2025-11-08 00:10:12.583799 | 
2025-11-08 00:10:12.583915 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-08 00:10:12.843699 | controller -> localhost | ok
2025-11-08 00:10:12.850652 | 
2025-11-08 00:10:12.850736 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-08 00:10:12.882967 | controller | ok
2025-11-08 00:10:12.894272 | controller | included: /var/lib/zuul/builds/9599ce379eae46d0b45cdb1b3c8b2d43/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-08 00:10:12.900808 | 
2025-11-08 00:10:12.900908 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-08 00:10:13.194554 | controller -> localhost | Generating public/private ecdsa key pair.
2025-11-08 00:10:13.194843 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9599ce379eae46d0b45cdb1b3c8b2d43/work/9599ce379eae46d0b45cdb1b3c8b2d43_id_ecdsa.
2025-11-08 00:10:13.194903 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9599ce379eae46d0b45cdb1b3c8b2d43/work/9599ce379eae46d0b45cdb1b3c8b2d43_id_ecdsa.pub.
2025-11-08 00:10:13.194965 | controller -> localhost | The key fingerprint is:
2025-11-08 00:10:13.195010 | controller -> localhost | SHA256:c8MJdLw/PgSmNuLfkoXp1WSvLIN6QXbioMKlHRqHs3Y zuul-build-sshkey
2025-11-08 00:10:13.195108 | controller -> localhost | The key's randomart image is:
2025-11-08 00:10:13.195155 | controller -> localhost | +---[ECDSA 521]---+
2025-11-08 00:10:13.195202 | controller -> localhost | |        ...      |
2025-11-08 00:10:13.195263 | controller -> localhost | |       . ..      |
2025-11-08 00:10:13.195334 | controller -> localhost | |   .    .  .     |
2025-11-08 00:10:13.195399 | controller -> localhost | |  + + . +o=.o    |
2025-11-08 00:10:13.195443 | controller -> localhost | | . X o =SB=* .   |
2025-11-08 00:10:13.195483 | controller -> localhost | |  B E . Ooo.= .  |
2025-11-08 00:10:13.195522 | controller -> localhost | | . o . + B + o   |
2025-11-08 00:10:13.195561 | controller -> localhost | |      . *.o =    |
2025-11-08 00:10:13.195605 | controller -> localhost | |      .+...o .   |
2025-11-08 00:10:13.195662 | controller -> localhost | +----[SHA256]-----+
2025-11-08 00:10:13.195794 | controller -> localhost | ok: Runtime: 0:00:00.014138
2025-11-08 00:10:13.210901 | 
2025-11-08 00:10:13.211067 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-08 00:10:13.239704 | controller | ok
2025-11-08 00:10:13.256299 | controller | included: /var/lib/zuul/builds/9599ce379eae46d0b45cdb1b3c8b2d43/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-08 00:10:13.271482 | 
2025-11-08 00:10:13.271592 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-08 00:10:13.296898 | controller | skipping: Conditional result was False
2025-11-08 00:10:13.307425 | 
2025-11-08 00:10:13.307534 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-08 00:10:14.250251 | controller | changed
2025-11-08 00:10:14.259000 | 
2025-11-08 00:10:14.259135 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-08 00:10:15.000260 | controller | ok
2025-11-08 00:10:15.010091 | 
2025-11-08 00:10:15.010206 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-08 00:10:18.167580 | controller | changed
2025-11-08 00:10:18.177909 | 
2025-11-08 00:10:18.178095 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-08 00:10:21.326186 | controller | changed
2025-11-08 00:10:21.344294 | 
2025-11-08 00:10:21.344489 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-08 00:10:21.382983 | controller | skipping: Conditional result was False
2025-11-08 00:10:21.404856 | 
2025-11-08 00:10:21.405105 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-08 00:10:21.660196 | controller -> localhost | changed
2025-11-08 00:10:21.690724 | 
2025-11-08 00:10:21.690965 | TASK [add-build-sshkey : Add back temp key]
2025-11-08 00:10:22.047417 | controller -> localhost | Identity added: /var/lib/zuul/builds/9599ce379eae46d0b45cdb1b3c8b2d43/work/9599ce379eae46d0b45cdb1b3c8b2d43_id_ecdsa (zuul-build-sshkey)
2025-11-08 00:10:22.047762 | controller -> localhost | ok: Runtime: 0:00:00.007281
2025-11-08 00:10:22.061501 | 
2025-11-08 00:10:22.061640 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-08 00:10:22.775643 | controller | ok
2025-11-08 00:10:22.786755 | 
2025-11-08 00:10:22.786970 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-08 00:10:22.825522 | controller | skipping: Conditional result was False
2025-11-08 00:10:22.860153 | 
2025-11-08 00:10:22.860318 | TASK [include_role : remove-zuul-sshkey]
2025-11-08 00:10:22.886831 | controller | skipping: Conditional result was False
2025-11-08 00:10:22.896256 | 
2025-11-08 00:10:22.896349 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-08 00:10:23.599926 | controller | ok: "logs"
2025-11-08 00:10:23.600695 | controller | ok: All items complete
2025-11-08 00:10:23.600764 | 
2025-11-08 00:10:24.255000 | controller | ok: "artifacts"
2025-11-08 00:10:24.897215 | controller | ok: "docs"
2025-11-08 00:10:24.918448 | 
2025-11-08 00:10:24.918655 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-08 00:10:25.601045 | controller | changed: "logs"
2025-11-08 00:10:26.302168 | controller | changed: "artifacts"
2025-11-08 00:10:26.929753 | controller | changed: "docs"
2025-11-08 00:10:26.991371 | 
2025-11-08 00:10:26.991499 | PLAY RECAP
2025-11-08 00:10:26.991556 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-11-08 00:10:26.991592 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-08 00:10:26.991615 | 
2025-11-08 00:10:27.146542 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-08 00:10:27.148971 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-08 00:10:27.812251 | 
2025-11-08 00:10:27.812377 | PLAY [all]
2025-11-08 00:10:27.835740 | 
2025-11-08 00:10:27.835867 | TASK [Install binary dependencies]
2025-11-08 00:10:27.889144 | controller | ok
2025-11-08 00:10:27.920085 | 
2025-11-08 00:10:27.920279 | TASK [bindep : Include find tasks]
2025-11-08 00:10:27.951644 | controller | ok
2025-11-08 00:10:27.959376 | controller | included: /var/lib/zuul/builds/9599ce379eae46d0b45cdb1b3c8b2d43/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-11-08 00:10:27.966200 | 
2025-11-08 00:10:27.966271 | TASK [bindep : Look for bindep.txt]
2025-11-08 00:10:28.920817 | controller | ok
2025-11-08 00:10:28.934167 | 
2025-11-08 00:10:28.934311 | TASK [bindep : Define bindep_file fact]
2025-11-08 00:10:28.953006 | controller | skipping: Conditional result was False
2025-11-08 00:10:28.967590 | 
2025-11-08 00:10:28.967757 | TASK [bindep : Look for other-requirements.txt]
2025-11-08 00:10:29.637836 | controller | ok
2025-11-08 00:10:29.652620 | 
2025-11-08 00:10:29.652801 | TASK [bindep : Define bindep_file fact]
2025-11-08 00:10:29.680479 | controller | skipping: Conditional result was False
2025-11-08 00:10:29.694327 | 
2025-11-08 00:10:29.694474 | TASK [bindep : Look for bindep fallback file]
2025-11-08 00:10:29.723218 | controller | skipping: Conditional result was False
2025-11-08 00:10:29.739415 | 
2025-11-08 00:10:29.739616 | TASK [bindep : Define bindep_file fact]
2025-11-08 00:10:29.767695 | controller | skipping: Conditional result was False
2025-11-08 00:10:29.781655 | 
2025-11-08 00:10:29.782111 | TASK [bindep : Include bindep tasks]
2025-11-08 00:10:29.809568 | controller | skipping: Conditional result was False
2025-11-08 00:10:29.823796 | 
2025-11-08 00:10:29.823938 | TASK [bindep : Include install tasks]
2025-11-08 00:10:29.851513 | controller | skipping: Conditional result was False
2025-11-08 00:10:29.867302 | 
2025-11-08 00:10:29.867462 | LOOP [bindep : Include package tasks]
2025-11-08 00:10:29.955345 | 
2025-11-08 00:10:29.955627 | TASK [Run test-setup role]
2025-11-08 00:10:29.980827 | controller | ok
2025-11-08 00:10:30.023275 | 
2025-11-08 00:10:30.023405 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-11-08 00:10:30.682052 | controller | ok
2025-11-08 00:10:30.695274 | 
2025-11-08 00:10:30.695441 | TASK [test-setup : Run tools/test-setup.sh]
2025-11-08 00:10:31.071156 | controller | skipping: Conditional result was False
2025-11-08 00:10:31.127767 | 
2025-11-08 00:10:31.127950 | PLAY RECAP
2025-11-08 00:10:31.128043 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-08 00:10:31.128081 | 
2025-11-08 00:10:31.245193 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-08 00:10:31.246704 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-08 00:10:31.870205 | 
2025-11-08 00:10:31.870326 | PLAY [controller]
2025-11-08 00:10:31.889959 | 
2025-11-08 00:10:31.890052 | TASK [Create the /root directory]
2025-11-08 00:10:33.018456 | controller | ok
2025-11-08 00:10:33.034970 | 
2025-11-08 00:10:33.035251 | TASK [Install glibc-langpack-en]
2025-11-08 00:10:41.540963 | controller | ok: Nothing to do
2025-11-08 00:10:41.548724 | 
2025-11-08 00:10:41.548818 | TASK [Ensure controller directory exists]
2025-11-08 00:10:42.362141 | controller | changed
2025-11-08 00:10:42.370813 | 
2025-11-08 00:10:42.370982 | TASK [Install container runtime]
2025-11-08 00:10:42.445693 | controller | ok
2025-11-08 00:10:42.503539 | 
2025-11-08 00:10:42.503658 | LOOP [ensure-docker : Gather variables for each operating system]
2025-11-08 00:10:42.567151 | controller | ok: "/var/lib/zuul/builds/9599ce379eae46d0b45cdb1b3c8b2d43/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-11-08 00:10:42.585104 | 
2025-11-08 00:10:42.585230 | TASK [ensure-docker : Sanity check for distro selection]
2025-11-08 00:10:42.609549 | controller | skipping: Conditional result was False
2025-11-08 00:10:42.617635 | 
2025-11-08 00:10:42.617711 | TASK [ensure-docker : Sanity check for distro install]
2025-11-08 00:10:42.641817 | controller | skipping: Conditional result was False
2025-11-08 00:10:42.648550 | 
2025-11-08 00:10:42.648656 | TASK [ensure-docker : Sanity check for upstream install]
2025-11-08 00:10:42.672771 | controller | skipping: Conditional result was False
2025-11-08 00:10:42.679523 | 
2025-11-08 00:10:42.679646 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-11-08 00:10:42.703724 | controller | skipping: Conditional result was False
2025-11-08 00:10:42.710578 | 
2025-11-08 00:10:42.710699 | TASK [ensure-docker : Include tasks for docker install]
2025-11-08 00:10:42.731502 | controller | ok
2025-11-08 00:10:42.740622 | controller | included: /var/lib/zuul/builds/9599ce379eae46d0b45cdb1b3c8b2d43/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-11-08 00:10:42.750777 | 
2025-11-08 00:10:42.750904 | TASK [ensure-docker : Not Implemented]
2025-11-08 00:10:42.765468 | controller | skipping: Conditional result was False
2025-11-08 00:10:42.776270 | 
2025-11-08 00:10:42.776397 | TASK [Set up docker mirrors]
2025-11-08 00:10:42.800228 | controller | ok
2025-11-08 00:10:42.828050 | 
2025-11-08 00:10:42.828254 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-11-08 00:10:42.853058 | controller | skipping: Conditional result was False
2025-11-08 00:10:42.861600 | 
2025-11-08 00:10:42.861700 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-11-08 00:10:42.926581 | controller | skipping: Conditional result was False
2025-11-08 00:10:42.960716 | 
2025-11-08 00:10:42.960838 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-11-08 00:10:42.987682 | controller | skipping: Conditional result was False
2025-11-08 00:10:42.994942 | 
2025-11-08 00:10:42.995040 | TASK [use-docker-mirror : Set up docker mirrors]
2025-11-08 00:10:43.029045 | controller | skipping: Conditional result was False
2025-11-08 00:10:43.041759 | 
2025-11-08 00:10:43.041858 | TASK [Add all repositories]
2025-11-08 00:10:43.064752 | controller | ok
2025-11-08 00:10:43.097794 | 
2025-11-08 00:10:43.097917 | LOOP [ensure-package-repositories : Install repositories]
2025-11-08 00:10:43.131235 | controller | ok: "/var/lib/zuul/builds/9599ce379eae46d0b45cdb1b3c8b2d43/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-11-08 00:10:43.131486 | controller | ok: All items complete
2025-11-08 00:10:43.131518 | 
2025-11-08 00:10:43.141757 | controller | included: /var/lib/zuul/builds/9599ce379eae46d0b45cdb1b3c8b2d43/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-11-08 00:10:43.148401 | 
2025-11-08 00:10:43.148503 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-11-08 00:10:44.100183 | controller | changed
2025-11-08 00:10:44.115705 | 
2025-11-08 00:10:44.115853 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-11-08 00:10:47.521561 | controller | changed:
2025-11-08 00:10:47.521826 | controller | {
2025-11-08 00:10:47.521883 | 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-11-08 00:10:47.521932 | controller | }
2025-11-08 00:10:47.530333 | 
2025-11-08 00:10:47.530467 | LOOP [ensure-package-repositories : Import GPG keys]
2025-11-08 00:10:48.913861 | controller | changed:
2025-11-08 00:10:48.914051 | controller | {
2025-11-08 00:10:48.914082 | 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-11-08 00:10:48.914106 | controller | }
2025-11-08 00:10:48.914133 | controller | changed: All items complete
2025-11-08 00:10:48.914153 | 
2025-11-08 00:10:48.929050 | 
2025-11-08 00:10:48.929184 | TASK [ensure-package-repositories : Remove GPG temp directory]
2025-11-08 00:10:49.597885 | controller | changed
2025-11-08 00:10:49.603915 | 
2025-11-08 00:10:49.603977 | LOOP [ensure-package-repositories : Add repositories]
2025-11-08 00:10:50.530933 | controller | changed:
2025-11-08 00:10:50.531482 | controller | {
2025-11-08 00:10:50.531554 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2025-11-08 00:10:50.531598 | controller |   "description": "Docker CE Stable - $basearch",
2025-11-08 00:10:50.531637 | controller |   "gpgcheck": true,
2025-11-08 00:10:50.531674 | controller |   "name": "docker-ce-stable"
2025-11-08 00:10:50.531709 | controller | }
2025-11-08 00:10:50.576247 | 
2025-11-08 00:10:50.576445 | TASK [ensure-docker : Install pre-reqs]
2025-11-08 00:10:55.758935 | controller | ok: Nothing to do
2025-11-08 00:10:55.781560 | 
2025-11-08 00:10:55.781803 | TASK [ensure-docker : Remove packages]
2025-11-08 00:10:58.949341 | controller | ok: Nothing to do
2025-11-08 00:10:58.958229 | 
2025-11-08 00:10:58.958377 | TASK [ensure-docker : Install upstream docker using package]
2025-11-08 00:12:26.280501 | controller | changed
2025-11-08 00:12:26.294250 | 
2025-11-08 00:12:26.294383 | TASK [ensure-docker : Setup docker]
2025-11-08 00:12:26.335918 | controller | ok
2025-11-08 00:12:26.357302 | controller | included: /var/lib/zuul/builds/9599ce379eae46d0b45cdb1b3c8b2d43/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2025-11-08 00:12:26.414488 | 
2025-11-08 00:12:26.414650 | TASK [ensure-docker : Ensure "docker" group exists]
2025-11-08 00:12:27.373305 | controller | ok
2025-11-08 00:12:27.388857 | 
2025-11-08 00:12:27.389081 | TASK [ensure-docker : Add user to docker group]
2025-11-08 00:12:28.370831 | controller | changed
2025-11-08 00:12:28.390294 | 
2025-11-08 00:12:28.390469 | TASK [Update docker daemon proxy configuration]
2025-11-08 00:12:28.426735 | controller | skipping: Conditional result was False
2025-11-08 00:12:28.436541 | 
2025-11-08 00:12:28.436667 | TASK [Lower default MTU]
2025-11-08 00:12:28.461965 | controller | skipping: Conditional result was False
2025-11-08 00:12:28.473001 | 
2025-11-08 00:12:28.473191 | TASK [ensure-docker : Get the status of the docket.socket unit]
2025-11-08 00:12:29.221111 | controller | inactive
2025-11-08 00:12:29.836627 | controller | ok: Runtime: 0:00:00.017993
2025-11-08 00:12:29.845443 | 
2025-11-08 00:12:29.845537 | TASK [ensure-docker : Get the status of the docket unit]
2025-11-08 00:12:30.768047 | controller | inactive
2025-11-08 00:12:31.210618 | controller | ok: Runtime: 0:00:00.038160
2025-11-08 00:12:31.226370 | 
2025-11-08 00:12:31.226536 | TASK [ensure-docker : Restart docker]
2025-11-08 00:12:31.299759 | Trigger a Docker restart
2025-11-08 00:12:31.315184 | 
2025-11-08 00:12:31.315336 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2025-11-08 00:12:31.378123 | 
2025-11-08 00:12:31.378292 | TASK [ensure-docker : Install docker-compose]
2025-11-08 00:12:31.403459 | controller | skipping: Conditional result was False
2025-11-08 00:12:31.413712 | 
2025-11-08 00:12:31.413822 | TASK [ensure-docker : Flush handlers before role exit]
2025-11-08 00:12:31.426345 | 
2025-11-08 00:12:31.426441 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2025-11-08 00:12:34.772221 | controller | changed
2025-11-08 00:12:34.790460 | 
2025-11-08 00:12:34.790620 | TASK [ensure-docker : Assure docker service is running]
2025-11-08 00:12:38.493094 | controller | changed
2025-11-08 00:12:38.506178 | 
2025-11-08 00:12:38.506316 | TASK [ensure-docker : Assure docker.socket service is running]
2025-11-08 00:12:40.166071 | controller | ok
2025-11-08 00:12:40.184830 | 
2025-11-08 00:12:40.184976 | TASK [ensure-docker : Correct group ownership on docker sock]
2025-11-08 00:12:41.020122 | controller | ok
2025-11-08 00:12:41.034868 | 
2025-11-08 00:12:41.035050 | TASK [ensure-docker : Validate ability to talk with docker]
2025-11-08 00:12:41.685970 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2025-11-08 00:12:41.905528 | controller | ok: Runtime: 0:00:00.045786
2025-11-08 00:12:41.917997 | 
2025-11-08 00:12:41.918180 | TASK [ensure-docker : Get version details]
2025-11-08 00:12:42.582280 | controller | Client: Docker Engine - Community
2025-11-08 00:12:42.582357 | controller |  Version:           24.0.7
2025-11-08 00:12:42.582367 | controller |  API version:       1.43
2025-11-08 00:12:42.582374 | controller |  Go version:        go1.20.10
2025-11-08 00:12:42.582396 | controller |  Git commit:        afdd53b
2025-11-08 00:12:42.582453 | controller |  Built:             Thu Oct 26 09:09:39 2023
2025-11-08 00:12:42.582462 | controller |  OS/Arch:           linux/amd64
2025-11-08 00:12:42.582470 | controller |  Context:           default
2025-11-08 00:12:42.582480 | controller |
2025-11-08 00:12:42.582486 | controller | Server: Docker Engine - Community
2025-11-08 00:12:42.582493 | controller |  Engine:
2025-11-08 00:12:42.582499 | controller |   Version:          24.0.7
2025-11-08 00:12:42.582512 | controller |   API version:      1.43 (minimum version 1.12)
2025-11-08 00:12:42.582518 | controller |   Go version:       go1.20.10
2025-11-08 00:12:42.582524 | controller |   Git commit:       311b9ff
2025-11-08 00:12:42.582530 | controller |   Built:            Thu Oct 26 09:08:11 2023
2025-11-08 00:12:42.582536 | controller |   OS/Arch:          linux/amd64
2025-11-08 00:12:42.582542 | controller |   Experimental:     false
2025-11-08 00:12:42.582548 | controller |  containerd:
2025-11-08 00:12:42.582558 | controller |   Version:          1.6.25
2025-11-08 00:12:42.585927 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2025-11-08 00:12:42.585942 | controller |  runc:
2025-11-08 00:12:42.586076 | controller |   Version:          1.1.10
2025-11-08 00:12:42.586088 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2025-11-08 00:12:42.586098 | controller |  docker-init:
2025-11-08 00:12:42.586104 | controller |   Version:          0.19.0
2025-11-08 00:12:42.586110 | controller |   GitCommit:        de40ad0
2025-11-08 00:12:42.816933 | controller | ok: Runtime: 0:00:00.044106
2025-11-08 00:12:42.826438 | 
2025-11-08 00:12:42.826544 | TASK [ensure-docker : Dump installed docker details]
2025-11-08 00:12:42.860885 | {'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-11-08 00:12:42.542380', 'end': '2025-11-08 00:12:42.586486', 'delta': '0:00:00.044106', 'msg': '', 'zuul_log_id': 'fa163ec2-ffbe-1d08-2bdb-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-11-08 00:12:42.870980 | 
2025-11-08 00:12:42.871160 | TASK [Use buildset registry]
2025-11-08 00:12:42.898444 | controller | skipping: Conditional result was False
2025-11-08 00:12:42.918446 | 
2025-11-08 00:12:42.918533 | TASK [Ensure python3.8 is present]
2025-11-08 00:12:42.933262 | controller | skipping: Conditional result was False
2025-11-08 00:12:42.941411 | 
2025-11-08 00:12:42.941499 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-11-08 00:12:42.963138 | controller | ok
2025-11-08 00:12:42.988384 | 
2025-11-08 00:12:42.988501 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-11-08 00:12:48.888677 | controller | ok: Nothing to do
2025-11-08 00:12:48.899052 | 
2025-11-08 00:12:48.899163 | TASK [our-ensure-python : Also install python3-devel]
2025-11-08 00:13:07.940000 | controller | changed
2025-11-08 00:13:07.965602 | 
2025-11-08 00:13:07.965723 | TASK [Run ensure-virtualenv role]
2025-11-08 00:13:07.994422 | controller | ok
2025-11-08 00:13:08.029988 | 
2025-11-08 00:13:08.030146 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-11-08 00:13:08.795258 | controller | /usr/bin/virtualenv
2025-11-08 00:13:09.399710 | controller | ok: Runtime: 0:00:00.004457
2025-11-08 00:13:09.413302 | 
2025-11-08 00:13:09.413459 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-11-08 00:13:09.450056 | controller | skipping: Conditional result was False
2025-11-08 00:13:09.451148 | controller | changed: All items complete
2025-11-08 00:13:09.451256 | 
2025-11-08 00:13:09.485418 | 
2025-11-08 00:13:09.485645 | TASK [Find the full path of the Python interpreter]
2025-11-08 00:13:10.301215 | controller | /usr/bin/python3.8
2025-11-08 00:13:10.860089 | controller | ok
2025-11-08 00:13:10.875740 | 
2025-11-08 00:13:10.875913 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-11-08 00:13:13.770951 | controller | created virtual environment CPython3.8.18.final.0-64 in 1429ms
2025-11-08 00:13:13.890864 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-11-08 00:13:13.890904 | 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-11-08 00:13:13.890922 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-11-08 00:13:13.890944 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-11-08 00:13:14.272238 | controller | changed
2025-11-08 00:13:14.279893 | 
2025-11-08 00:13:14.280091 | TASK [Set selinux package]
2025-11-08 00:13:14.308518 | controller | ok
2025-11-08 00:13:14.317959 | 
2025-11-08 00:13:14.318086 | TASK [Set selinux package (Fedora)]
2025-11-08 00:13:14.366517 | controller | ok
2025-11-08 00:13:14.372640 | 
2025-11-08 00:13:14.372750 | TASK [Install selinux into virtualenv]
2025-11-08 00:13:20.181664 | controller | Collecting selinux-please-lie-to-me
2025-11-08 00:13:20.295768 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-11-08 00:13:21.223902 | controller | Collecting setuptools<50.0.0
2025-11-08 00:13:21.239898 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-11-08 00:13:21.318968 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 10.8 MB/s eta 0:00:00
2025-11-08 00:13:21.455028 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-11-08 00:13:21.455380 | controller |   Attempting uninstall: setuptools
2025-11-08 00:13:21.456584 | controller |     Found existing installation: setuptools 62.6.0
2025-11-08 00:13:21.589081 | controller |     Uninstalling setuptools-62.6.0:
2025-11-08 00:13:21.607196 | controller |       Successfully uninstalled setuptools-62.6.0
2025-11-08 00:13:22.364444 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-11-08 00:13:22.588190 | controller |
2025-11-08 00:13:22.875205 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-11-08 00:13:22.875285 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-11-08 00:13:23.371845 | controller | ok: Runtime: 0:00:07.373283
2025-11-08 00:13:23.378344 | 
2025-11-08 00:13:23.378428 | TASK [Install pytest-forked into virtualenv]
2025-11-08 00:13:25.167325 | controller | Collecting pytest-forked
2025-11-08 00:13:25.224914 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-11-08 00:13:25.450033 | controller | Collecting pytest>=3.10
2025-11-08 00:13:25.458329 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2025-11-08 00:13:25.500151 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 9.3 MB/s eta 0:00:00
2025-11-08 00:13:25.563402 | controller | Collecting py
2025-11-08 00:13:25.568599 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-11-08 00:13:25.584577 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 7.2 MB/s eta 0:00:00
2025-11-08 00:13:25.670322 | controller | Collecting exceptiongroup>=1.0.0rc8
2025-11-08 00:13:25.673869 | controller |   Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB)
2025-11-08 00:13:25.732051 | controller | Collecting pluggy<2,>=1.5
2025-11-08 00:13:25.736658 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-11-08 00:13:25.813417 | controller | Collecting packaging
2025-11-08 00:13:25.821344 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-11-08 00:13:25.831231 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 12.5 MB/s eta 0:00:00
2025-11-08 00:13:25.902137 | controller | Collecting tomli>=1
2025-11-08 00:13:25.907870 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2025-11-08 00:13:25.948733 | controller | Collecting iniconfig
2025-11-08 00:13:25.956368 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-11-08 00:13:26.035718 | controller | Collecting typing-extensions>=4.6.0
2025-11-08 00:13:26.043446 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2025-11-08 00:13:26.053520 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 5.8 MB/s eta 0:00:00
2025-11-08 00:13:26.154683 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-11-08 00:13:27.010291 | controller | Successfully installed exceptiongroup-1.3.0 iniconfig-2.1.0 packaging-25.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.3.0 typing-extensions-4.13.2
2025-11-08 00:13:27.016222 | controller |
2025-11-08 00:13:27.216274 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-11-08 00:13:27.216329 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-11-08 00:13:27.743832 | controller | ok: Runtime: 0:00:02.818759
2025-11-08 00:13:27.753674 | 
2025-11-08 00:13:27.753811 | TASK [Update pip]
2025-11-08 00:13:30.054219 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2025-11-08 00:13:30.574045 | controller | Collecting pip
2025-11-08 00:13:30.724862 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2025-11-08 00:13:30.868866 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 13.8 MB/s eta 0:00:00
2025-11-08 00:13:31.036133 | controller | Installing collected packages: pip
2025-11-08 00:13:31.036366 | controller |   Attempting uninstall: pip
2025-11-08 00:13:31.038188 | controller |     Found existing installation: pip 22.2.2
2025-11-08 00:13:31.589122 | controller |     Uninstalling pip-22.2.2:
2025-11-08 00:13:31.663121 | controller |       Successfully uninstalled pip-22.2.2
2025-11-08 00:13:34.244147 | controller | Successfully installed pip-25.0.1
2025-11-08 00:13:34.632903 | controller | ok: Runtime: 0:00:05.572570
2025-11-08 00:13:34.641351 | 
2025-11-08 00:13:34.641440 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-11-08 00:13:35.693511 | controller | changed
2025-11-08 00:13:35.707504 | 
2025-11-08 00:13:35.707696 | TASK [Install ansible into virtualenv]
2025-11-08 00:13:37.900851 | controller | Processing ./src/github.com/ansible/ansible
2025-11-08 00:13:37.903854 | controller |   Preparing metadata (setup.py): started
2025-11-08 00:13:38.715065 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-11-08 00:13:38.782391 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2025-11-08 00:13:38.797600 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-11-08 00:13:38.884225 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2025-11-08 00:13:38.890315 | 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-11-08 00:13:39.333183 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2025-11-08 00:13:39.342628 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-11-08 00:13:39.366584 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (25.0)
2025-11-08 00:13:39.590244 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2025-11-08 00:13:39.592177 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2025-11-08 00:13:39.603212 | 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-11-08 00:13:39.715482 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2025-11-08 00:13:39.721043 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2025-11-08 00:13:39.756842 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2025-11-08 00:13:39.763867 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-11-08 00:13:39.788858 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2025-11-08 00:13:39.852213 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 80.4 MB/s eta 0:00:00
2025-11-08 00:13:39.858790 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-11-08 00:13:39.875424 | 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-11-08 00:13:39.896311 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 35.8 MB/s eta 0:00:00
2025-11-08 00:13:39.904024 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2025-11-08 00:13:39.921805 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2025-11-08 00:13:39.934627 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-11-08 00:13:39.962988 | controller | Building wheels for collected packages: ansible-base
2025-11-08 00:13:39.964038 | controller |   Building wheel for ansible-base (setup.py): started
2025-11-08 00:13:42.374669 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2025-11-08 00:13:42.379324 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=72df11cdf496342777a12ea20b83df872500cf9227242c941f5287e97d5d5a69
2025-11-08 00:13:42.379697 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-l0jqwj0s/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2025-11-08 00:13:42.394348 | controller | Successfully built ansible-base
2025-11-08 00:13:42.456308 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2025-11-08 00:13:45.667647 | 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-11-08 00:13:46.099867 | controller | ok: Runtime: 0:00:09.134157
2025-11-08 00:13:46.109994 | 
2025-11-08 00:13:46.110162 | TASK [Setup download-artifact-fork role]
2025-11-08 00:13:46.133611 | controller | ok
2025-11-08 00:13:46.160409 | 
2025-11-08 00:13:46.160606 | TASK [download-artifact-fork : Parse inventory response]
2025-11-08 00:13:46.191924 | controller | ok
2025-11-08 00:13:46.199694 | 
2025-11-08 00:13:46.199813 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-11-08 00:13:46.224251 | controller | skipping: Conditional result was False
2025-11-08 00:13:46.231004 | 
2025-11-08 00:13:46.231133 | TASK [download-artifact-fork : Parse build response]
2025-11-08 00:13:46.255605 | controller | skipping: Conditional result was False
2025-11-08 00:13:46.265558 | 
2025-11-08 00:13:46.265718 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-11-08 00:13:47.030927 | controller | changed
2025-11-08 00:13:47.042092 | 
2025-11-08 00:13:47.042257 | LOOP [download-artifact-fork : Download archive by type]
2025-11-08 00:13:47.073004 | controller | skipping: Conditional result was False
2025-11-08 00:13:47.073354 | 
2025-11-08 00:13:50.015851 | controller | changed: OK (20212 bytes)
2025-11-08 00:13:50.057951 | 
2025-11-08 00:13:50.058516 | TASK [deploy-artifacts : define __collections]
2025-11-08 00:13:50.094692 | controller | ok
2025-11-08 00:13:50.102078 | 
2025-11-08 00:13:50.102171 | LOOP [deploy-artifacts : Create list of collections]
2025-11-08 00:13:50.136936 | controller | skipping: Conditional result was False
2025-11-08 00:13:50.150536 | controller | ok:
2025-11-08 00:13:50.150685 | controller | {
2025-11-08 00:13:50.150724 | controller |   "branch": "main",
2025-11-08 00:13:50.150748 | controller |   "job": "build-ansible-collection",
2025-11-08 00:13:50.150771 | controller |   "metadata": {
2025-11-08 00:13:50.150792 | controller |     "type": "ansible_collection",
2025-11-08 00:13:50.150813 | controller |     "version": "5.0.0"
2025-11-08 00:13:50.150834 | controller |   },
2025-11-08 00:13:50.150855 | controller |   "name": "ansible.network",
2025-11-08 00:13:50.150875 | controller |   "project": "ansible-collections/ansible.network",
2025-11-08 00:13:50.150896 | controller |   "url": "https://9633d17efd53a144cea1-6fa346a2baf72bd4ffcc4b3619ee210f.ssl.cf5.rackcdn.com/ansible/36cf9bf5280443ac938983d1ee9e9148/artifacts/ansible-network-5.0.0.tar.gz"
2025-11-08 00:13:50.150926 | controller | }
2025-11-08 00:13:50.170364 | 
2025-11-08 00:13:50.170536 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-11-08 00:13:51.799786 | controller | Starting galaxy collection install process
2025-11-08 00:13:51.800352 | controller | Process install dependency map
2025-11-08 00:14:06.329608 | controller | Starting collection install process
2025-11-08 00:14:06.345095 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2025-11-08 00:14:06.345186 | controller | ansible.network (5.0.0) was installed successfully
2025-11-08 00:14:08.025983 | controller | Installing 'ansible.netcommon:8.2.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2025-11-08 00:14:08.026088 | 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-41036yiq48pb/tmpcdmejq2a
2025-11-08 00:14:08.026138 | controller | ansible.netcommon (8.2.0) was installed successfully
2025-11-08 00:14:09.937742 | controller | Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2025-11-08 00:14:09.937827 | 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-41036yiq48pb/tmpcdmejq2a
2025-11-08 00:14:09.937859 | controller | ansible.utils (6.0.0) was installed successfully
2025-11-08 00:14:09.937982 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2025-11-08 00:14:09.937995 | 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-41036yiq48pb/tmpcdmejq2a
2025-11-08 00:14:12.974343 | controller | arista.eos (12.0.0) was installed successfully
2025-11-08 00:14:12.974416 | controller | Installing 'cisco.ios:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2025-11-08 00:14:12.976927 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-41036yiq48pb/tmpcdmejq2a
2025-11-08 00:14:16.164601 | controller | cisco.ios (11.1.1) was installed successfully
2025-11-08 00:14:16.164879 | controller | Installing 'cisco.iosxr:12.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2025-11-08 00:14:16.164896 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.1.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-41036yiq48pb/tmpcdmejq2a
2025-11-08 00:14:20.379000 | controller | cisco.iosxr (12.1.0) was installed successfully
2025-11-08 00:14:20.379301 | controller | Installing 'cisco.nxos:11.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2025-11-08 00:14:20.379319 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-41036yiq48pb/tmpcdmejq2a
2025-11-08 00:14:24.780316 | controller | cisco.nxos (11.1.0) was installed successfully
2025-11-08 00:14:27.329077 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2025-11-08 00:14:27.329143 | 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-41036yiq48pb/tmpcdmejq2a
2025-11-08 00:14:27.329159 | controller | junipernetworks.junos (11.0.0) was installed successfully
2025-11-08 00:14:27.609334 | controller | ok: Runtime: 0:00:36.411876
2025-11-08 00:14:27.636122 | 
2025-11-08 00:14:27.636292 | TASK [podman may need iptables kernel modules]
2025-11-08 00:14:29.029197 | controller | ok: Runtime: 0:00:00.013536
2025-11-08 00:14:29.049320 | 
2025-11-08 00:14:29.049404 | PLAY RECAP
2025-11-08 00:14:29.049453 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2025-11-08 00:14:29.049480 | 
2025-11-08 00:14:29.155691 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-08 00:14:29.156958 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-11-08 00:14:29.847385 | 
2025-11-08 00:14:29.847522 | PLAY [controller]
2025-11-08 00:14:29.872004 | 
2025-11-08 00:14:29.872152 | TASK [Copy the galaxy.yml on the controller]
2025-11-08 00:14:33.479807 | controller | changed
2025-11-08 00:14:33.503631 | 
2025-11-08 00:14:33.503795 | TASK [Load information from galaxy.yml]
2025-11-08 00:14:33.536877 | controller | ok
2025-11-08 00:14:33.545519 | 
2025-11-08 00:14:33.545614 | LOOP [Enable FIPS mode]
2025-11-08 00:14:33.569218 | 
2025-11-08 00:14:33.569465 | LOOP [ansible-test : Include branch specific variables.]
2025-11-08 00:14:33.607441 | controller | ok: "/var/lib/zuul/builds/9599ce379eae46d0b45cdb1b3c8b2d43/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2025-11-08 00:14:33.607625 | 
2025-11-08 00:14:33.624597 | 
2025-11-08 00:14:33.624717 | TASK [ansible-test : Setup --skip-tags for test_options]
2025-11-08 00:14:33.667312 | controller | ok
2025-11-08 00:14:33.674809 | 
2025-11-08 00:14:33.674896 | TASK [ansible-test : Enable --inventory for network-integration]
2025-11-08 00:14:33.699720 | controller | skipping: Conditional result was False
2025-11-08 00:14:33.709159 | 
2025-11-08 00:14:33.709253 | TASK [ansible-test : Enable --retry-on-error]
2025-11-08 00:14:33.734063 | controller | skipping: Conditional result was False
2025-11-08 00:14:33.742363 | 
2025-11-08 00:14:33.742457 | TASK [ansible-test : Enable --continue-on-error]
2025-11-08 00:14:33.784727 | controller | ok
2025-11-08 00:14:33.791980 | 
2025-11-08 00:14:33.792085 | TASK [ansible-test : Enable --requirements]
2025-11-08 00:14:33.816993 | controller | skipping: Conditional result was False
2025-11-08 00:14:33.826230 | 
2025-11-08 00:14:33.826322 | TASK [ansible-test : Adjust options for unit tests]
2025-11-08 00:14:33.851086 | controller | skipping: Conditional result was False
2025-11-08 00:14:33.859431 | 
2025-11-08 00:14:33.859518 | TASK [ansible-test : Install requirements with unit tests]
2025-11-08 00:14:33.893304 | controller | ok
2025-11-08 00:14:33.929409 | 
2025-11-08 00:14:33.929549 | TASK [ansible-test : Set --skip-test]
2025-11-08 00:14:33.983786 | controller | skipping: Conditional result was False
2025-11-08 00:14:33.990034 | 
2025-11-08 00:14:33.990099 | TASK [ansible-test : Reduce verbosity]
2025-11-08 00:14:34.019063 | controller | ok
2025-11-08 00:14:34.024438 | 
2025-11-08 00:14:34.024497 | TASK [ansible-test : Setup --python option]
2025-11-08 00:14:34.048258 | controller | skipping: Conditional result was False
2025-11-08 00:14:34.054310 | 
2025-11-08 00:14:34.054373 | TASK [ansible-test : Setup --docker option]
2025-11-08 00:14:34.083414 | controller | ok
2025-11-08 00:14:34.089733 | 
2025-11-08 00:14:34.089792 | TASK [ansible-test : check if MANIFEST.json exists]
2025-11-08 00:14:35.209410 | controller | ok
2025-11-08 00:14:35.222900 | 
2025-11-08 00:14:35.223008 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2025-11-08 00:14:35.247890 | controller | skipping: Conditional result was False
2025-11-08 00:14:35.257466 | 
2025-11-08 00:14:35.257576 | TASK [ansible-test : Load information from MANIFEST.json]
2025-11-08 00:14:35.281835 | controller | skipping: Conditional result was False
2025-11-08 00:14:35.291198 | 
2025-11-08 00:14:35.291308 | TASK [ansible-test : Setup location of project for integration tests]
2025-11-08 00:14:35.315938 | controller | skipping: Conditional result was False
2025-11-08 00:14:35.325496 | 
2025-11-08 00:14:35.325584 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2025-11-08 00:14:36.815468 | controller | ok
2025-11-08 00:14:36.828703 | 
2025-11-08 00:14:36.828840 | TASK [ansible-test : Load information from galaxy.yml]
2025-11-08 00:14:36.871454 | controller | ok
2025-11-08 00:14:36.891506 | 
2025-11-08 00:14:36.892141 | TASK [ansible-test : Setup location of project for integration tests]
2025-11-08 00:14:36.936594 | controller | ok
2025-11-08 00:14:36.944697 | 
2025-11-08 00:14:36.944792 | TASK [ansible-test : Setup minimum test requirements]
2025-11-08 00:14:36.978320 | controller | ok
2025-11-08 00:14:36.986120 | 
2025-11-08 00:14:36.986212 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2025-11-08 00:14:37.976723 | controller | ok
2025-11-08 00:14:37.991325 | 
2025-11-08 00:14:37.991506 | TASK [ansible-test : Install python requirements]
2025-11-08 00:14:39.522758 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-11-08 00:14:39.542431 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2025-11-08 00:14:39.583509 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-11-08 00:14:39.591866 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2025-11-08 00:14:39.603421 | 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-11-08 00:14:39.612061 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2025-11-08 00:14:39.639753 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2025-11-08 00:14:39.694782 | controller | Installing collected packages: pathspec, yamllint
2025-11-08 00:14:39.785412 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2025-11-08 00:14:40.362858 | controller | ok: Runtime: 0:00:01.038111
2025-11-08 00:14:40.369329 | 
2025-11-08 00:14:40.369399 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2025-11-08 00:14:42.392613 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2025-11-08 00:14:42.726660 | controller | ERROR
2025-11-08 00:14:42.726832 | controller | {
2025-11-08 00:14:42.726861 | controller |   "delta": "0:00:00.009684",
2025-11-08 00:14:42.726881 | controller |   "end": "2025-11-08 00:14:42.392925",
2025-11-08 00:14:42.726899 | controller |   "msg": "non-zero return code",
2025-11-08 00:14:42.726916 | controller |   "rc": 1,
2025-11-08 00:14:42.726933 | controller |   "start": "2025-11-08 00:14:42.383241"
2025-11-08 00:14:42.726949 | controller | }
2025-11-08 00:14:42.726971 | controller | ERROR: Ignoring Errors
2025-11-08 00:14:42.733585 | 
2025-11-08 00:14:42.733652 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2025-11-08 00:14:43.115444 | controller | skipping: Conditional result was False
2025-11-08 00:14:43.121991 | 
2025-11-08 00:14:43.122190 | TASK [ansible-test : Enable persistent connection logging]
2025-11-08 00:14:44.657087 | controller | changed: section and option added
2025-11-08 00:14:44.665103 | 
2025-11-08 00:14:44.665250 | TASK [ansible-test : Set persistent ssh_type value]
2025-11-08 00:14:45.902856 | controller | changed: option added
2025-11-08 00:14:45.916562 | 
2025-11-08 00:14:45.916752 | TASK [ansible-test : Set ansible log path]
2025-11-08 00:14:46.796290 | controller | changed: section and option added
2025-11-08 00:14:46.802787 | 
2025-11-08 00:14:46.802865 | TASK [ansible-test : Identify the changed targets]
2025-11-08 00:14:47.156371 | controller | skipping: Conditional result was False
2025-11-08 00:14:47.162710 | 
2025-11-08 00:14:47.162777 | TASK [ansible-test : set_fact]
2025-11-08 00:14:47.250434 | controller | skipping: Conditional result was False
2025-11-08 00:14:47.295745 | 
2025-11-08 00:14:47.295883 | TASK [ansible-test : Set the targets]
2025-11-08 00:14:47.325855 | controller | ok
2025-11-08 00:14:47.331497 | 
2025-11-08 00:14:47.331561 | TASK [ansible-test : set_fact]
2025-11-08 00:14:47.355189 | controller | skipping: Conditional result was False
2025-11-08 00:14:47.362060 | 
2025-11-08 00:14:47.362184 | TASK [ansible-test : List the targets]
2025-11-08 00:14:47.724191 | controller | skipping: Conditional result was False
2025-11-08 00:14:47.740558 | 
2025-11-08 00:14:47.740726 | TASK [ansible-test : set_fact]
2025-11-08 00:14:47.781488 | controller | skipping: Conditional result was False
2025-11-08 00:14:47.791614 | 
2025-11-08 00:14:47.791753 | TASK [ansible-test : set_fact]
2025-11-08 00:14:47.817954 | controller | skipping: Conditional result was False
2025-11-08 00:14:47.828841 | 
2025-11-08 00:14:47.828965 | LOOP [ansible-test : set_fact]
2025-11-08 00:14:47.892876 | 
2025-11-08 00:14:47.893084 | TASK [ansible-test : set_fact]
2025-11-08 00:14:47.916929 | controller | skipping: Conditional result was False
2025-11-08 00:14:47.925380 | 
2025-11-08 00:14:47.925466 | TASK [ansible-test : debug]
2025-11-08 00:14:47.949487 | controller | skipping: Conditional result was False
2025-11-08 00:14:47.956381 | 
2025-11-08 00:14:47.956460 | TASK [ansible-test : debug]
2025-11-08 00:14:47.988763 | About to run: ansible-test sanity --requirements --docker -v
2025-11-08 00:14:48.000514 | 
2025-11-08 00:14:48.000607 | TASK [ansible-test : Run the test suite]
2025-11-08 00:14:49.650752 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2025-11-08 00:14:49.737308 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2025-11-08 00:14:51.376483 | controller | 2.9.0: Pulling from ansible/default-test-container
2025-11-08 00:14:51.376530 | controller | 171857c49d0f: Pulling fs layer
2025-11-08 00:14:51.376552 | controller | 419640447d26: Pulling fs layer
2025-11-08 00:14:51.376560 | controller | 61e52f862619: Pulling fs layer
2025-11-08 00:14:51.376567 | controller | 851de0426e26: Pulling fs layer
2025-11-08 00:14:51.376576 | controller | 37425716ea5b: Pulling fs layer
2025-11-08 00:14:51.376585 | controller | cd9b0c439616: Pulling fs layer
2025-11-08 00:14:51.376591 | controller | d4612dbd8855: Pulling fs layer
2025-11-08 00:14:51.376597 | controller | 554699364730: Pulling fs layer
2025-11-08 00:14:51.376603 | controller | 1ff15d4bdb3e: Pulling fs layer
2025-11-08 00:14:51.376609 | controller | 5f2e4724adea: Pulling fs layer
2025-11-08 00:14:51.376622 | controller | a02d9a95ccb8: Pulling fs layer
2025-11-08 00:14:51.376628 | controller | 989f4ce370cb: Pulling fs layer
2025-11-08 00:14:51.376634 | controller | 0d7f52cdf9df: Pulling fs layer
2025-11-08 00:14:51.376640 | controller | c423742351d5: Pulling fs layer
2025-11-08 00:14:51.376646 | controller | 109236341d21: Pulling fs layer
2025-11-08 00:14:51.376651 | controller | 07a2f61c96b8: Pulling fs layer
2025-11-08 00:14:51.376657 | controller | 041f122f27ae: Pulling fs layer
2025-11-08 00:14:51.376663 | controller | ca6f5098b12e: Pulling fs layer
2025-11-08 00:14:51.376668 | controller | 1eb3e7b2afd9: Pulling fs layer
2025-11-08 00:14:51.376674 | controller | 18a0b750727a: Pulling fs layer
2025-11-08 00:14:51.376680 | controller | 17f2f596716c: Pulling fs layer
2025-11-08 00:14:51.376688 | controller | f35a9998a9bd: Pulling fs layer
2025-11-08 00:14:51.376694 | controller | fdfc10566405: Pulling fs layer
2025-11-08 00:14:51.376702 | controller | 1ff1c442c5fe: Pulling fs layer
2025-11-08 00:14:51.376708 | controller | a3443c4a17ed: Pulling fs layer
2025-11-08 00:14:51.376714 | controller | 0edcf9fb2b15: Pulling fs layer
2025-11-08 00:14:51.376719 | controller | 5af43b69e845: Pulling fs layer
2025-11-08 00:14:51.376725 | controller | 1651773effb2: Pulling fs layer
2025-11-08 00:14:51.376731 | controller | 79e23c5c0fe4: Pulling fs layer
2025-11-08 00:14:51.376740 | controller | e5058b1faaa6: Pulling fs layer
2025-11-08 00:14:51.376745 | controller | 846775490569: Pulling fs layer
2025-11-08 00:14:51.376751 | controller | 851de0426e26: Waiting
2025-11-08 00:14:51.376757 | controller | 37425716ea5b: Waiting
2025-11-08 00:14:51.376763 | controller | cd9b0c439616: Waiting
2025-11-08 00:14:51.376768 | controller | d4612dbd8855: Waiting
2025-11-08 00:14:51.376774 | controller | 554699364730: Waiting
2025-11-08 00:14:51.376780 | controller | 1ff15d4bdb3e: Waiting
2025-11-08 00:14:51.376789 | controller | 5f2e4724adea: Waiting
2025-11-08 00:14:51.376795 | controller | a02d9a95ccb8: Waiting
2025-11-08 00:14:51.376801 | controller | 989f4ce370cb: Waiting
2025-11-08 00:14:51.376807 | controller | 0d7f52cdf9df: Waiting
2025-11-08 00:14:51.376837 | controller | c423742351d5: Waiting
2025-11-08 00:14:51.376844 | controller | 109236341d21: Waiting
2025-11-08 00:14:51.376850 | controller | 07a2f61c96b8: Waiting
2025-11-08 00:14:51.376856 | controller | 041f122f27ae: Waiting
2025-11-08 00:14:51.376861 | controller | ca6f5098b12e: Waiting
2025-11-08 00:14:51.376867 | controller | 1eb3e7b2afd9: Waiting
2025-11-08 00:14:51.376873 | controller | 18a0b750727a: Waiting
2025-11-08 00:14:51.376878 | controller | 17f2f596716c: Waiting
2025-11-08 00:14:51.376887 | controller | f35a9998a9bd: Waiting
2025-11-08 00:14:51.376892 | controller | fdfc10566405: Waiting
2025-11-08 00:14:51.376898 | controller | 1ff1c442c5fe: Waiting
2025-11-08 00:14:51.376904 | controller | a3443c4a17ed: Waiting
2025-11-08 00:14:51.376909 | controller | 0edcf9fb2b15: Waiting
2025-11-08 00:14:51.376919 | controller | 5af43b69e845: Waiting
2025-11-08 00:14:51.784002 | controller | 1651773effb2: Waiting
2025-11-08 00:14:51.784053 | controller | 79e23c5c0fe4: Waiting
2025-11-08 00:14:51.784060 | controller | e5058b1faaa6: Waiting
2025-11-08 00:14:51.784067 | controller | 846775490569: Waiting
2025-11-08 00:14:51.784094 | controller | 419640447d26: Verifying Checksum
2025-11-08 00:14:51.818498 | controller | 419640447d26: Download complete
2025-11-08 00:14:51.818562 | controller | 61e52f862619: Verifying Checksum
2025-11-08 00:14:52.581229 | controller | 61e52f862619: Download complete
2025-11-08 00:14:52.581300 | controller | 851de0426e26: Verifying Checksum
2025-11-08 00:14:52.899248 | controller | 851de0426e26: Download complete
2025-11-08 00:14:52.899339 | controller | 171857c49d0f: Download complete
2025-11-08 00:14:53.418762 | controller | cd9b0c439616: Verifying Checksum
2025-11-08 00:14:53.419121 | controller | cd9b0c439616: Download complete
2025-11-08 00:14:53.674738 | controller | d4612dbd8855: Verifying Checksum
2025-11-08 00:14:53.674790 | controller | d4612dbd8855: Download complete
2025-11-08 00:14:54.506712 | controller | 1ff15d4bdb3e: Verifying Checksum
2025-11-08 00:14:55.294671 | controller | 1ff15d4bdb3e: Download complete
2025-11-08 00:14:55.294725 | controller | 5f2e4724adea: Verifying Checksum
2025-11-08 00:14:56.455552 | controller | 5f2e4724adea: Download complete
2025-11-08 00:14:56.455604 | controller | 171857c49d0f: Pull complete
2025-11-08 00:14:56.507081 | controller | 419640447d26: Pull complete
2025-11-08 00:14:56.543881 | controller | 61e52f862619: Pull complete
2025-11-08 00:14:56.573321 | controller | 851de0426e26: Pull complete
2025-11-08 00:14:56.789478 | controller | a02d9a95ccb8: Verifying Checksum
2025-11-08 00:14:57.612879 | controller | a02d9a95ccb8: Download complete
2025-11-08 00:14:57.612930 | controller | 989f4ce370cb: Verifying Checksum
2025-11-08 00:14:58.402802 | controller | 989f4ce370cb: Download complete
2025-11-08 00:14:58.402884 | controller | 0d7f52cdf9df: Verifying Checksum
2025-11-08 00:14:59.145373 | controller | 0d7f52cdf9df: Download complete
2025-11-08 00:14:59.145469 | controller | c423742351d5: Verifying Checksum
2025-11-08 00:15:08.026447 | controller | c423742351d5: Download complete
2025-11-08 00:15:08.026559 | controller | 554699364730: Verifying Checksum
2025-11-08 00:15:08.026598 | controller | 554699364730: Download complete
2025-11-08 00:15:08.719356 | controller | 109236341d21: Verifying Checksum
2025-11-08 00:15:08.719585 | controller | 109236341d21: Download complete
2025-11-08 00:15:08.769876 | controller | 07a2f61c96b8: Verifying Checksum
2025-11-08 00:15:08.769969 | controller | 07a2f61c96b8: Download complete
2025-11-08 00:15:09.461974 | controller | 041f122f27ae: Verifying Checksum
2025-11-08 00:15:09.462068 | controller | 041f122f27ae: Download complete
2025-11-08 00:15:10.108202 | controller | ca6f5098b12e: Verifying Checksum
2025-11-08 00:15:10.108498 | controller | ca6f5098b12e: Download complete
2025-11-08 00:15:10.739764 | controller | 1eb3e7b2afd9: Verifying Checksum
2025-11-08 00:15:10.744504 | controller | 1eb3e7b2afd9: Download complete
2025-11-08 00:15:11.561705 | controller | 18a0b750727a: Verifying Checksum
2025-11-08 00:15:11.566681 | controller | 18a0b750727a: Download complete
2025-11-08 00:15:11.566773 | controller | 17f2f596716c: Verifying Checksum
2025-11-08 00:15:12.284328 | controller | 17f2f596716c: Download complete
2025-11-08 00:15:12.284432 | controller | f35a9998a9bd: Verifying Checksum
2025-11-08 00:15:12.287365 | controller | f35a9998a9bd: Download complete
2025-11-08 00:15:12.296563 | controller | fdfc10566405: Verifying Checksum
2025-11-08 00:15:13.006589 | controller | fdfc10566405: Download complete
2025-11-08 00:15:13.006731 | controller | 1ff1c442c5fe: Verifying Checksum
2025-11-08 00:15:13.008636 | controller | 1ff1c442c5fe: Download complete
2025-11-08 00:15:15.157598 | controller | 0edcf9fb2b15: Verifying Checksum
2025-11-08 00:15:15.157764 | controller | 0edcf9fb2b15: Download complete
2025-11-08 00:15:18.241230 | controller | a3443c4a17ed: Verifying Checksum
2025-11-08 00:15:18.245511 | controller | a3443c4a17ed: Download complete
2025-11-08 00:15:20.894429 | controller | 5af43b69e845: Verifying Checksum
2025-11-08 00:15:20.894588 | controller | 5af43b69e845: Download complete
2025-11-08 00:15:21.296352 | controller | 1651773effb2: Verifying Checksum
2025-11-08 00:15:21.296589 | controller | 1651773effb2: Download complete
2025-11-08 00:15:23.997166 | controller | 79e23c5c0fe4: Verifying Checksum
2025-11-08 00:15:23.998662 | controller | 79e23c5c0fe4: Download complete
2025-11-08 00:15:24.190875 | controller | e5058b1faaa6: Verifying Checksum
2025-11-08 00:15:24.190995 | controller | e5058b1faaa6: Download complete
2025-11-08 00:15:24.762549 | controller | 37425716ea5b: Verifying Checksum
2025-11-08 00:15:24.762667 | controller | 37425716ea5b: Download complete
2025-11-08 00:15:33.480939 | controller | 846775490569: Verifying Checksum
2025-11-08 00:15:33.481040 | controller | 846775490569: Download complete
2025-11-08 00:15:45.013040 | controller | 37425716ea5b: Pull complete
2025-11-08 00:15:45.050227 | controller | cd9b0c439616: Pull complete
2025-11-08 00:15:45.070868 | controller | d4612dbd8855: Pull complete
2025-11-08 00:15:50.047905 | controller | 554699364730: Pull complete
2025-11-08 00:15:50.072033 | controller | 1ff15d4bdb3e: Pull complete
2025-11-08 00:15:50.097199 | controller | 5f2e4724adea: Pull complete
2025-11-08 00:15:50.154613 | controller | a02d9a95ccb8: Pull complete
2025-11-08 00:15:50.187086 | controller | 989f4ce370cb: Pull complete
2025-11-08 00:15:50.212187 | controller | 0d7f52cdf9df: Pull complete
2025-11-08 00:15:50.248672 | controller | c423742351d5: Pull complete
2025-11-08 00:15:52.716217 | controller | 109236341d21: Pull complete
2025-11-08 00:15:52.770198 | controller | 07a2f61c96b8: Pull complete
2025-11-08 00:15:52.928941 | controller | 041f122f27ae: Pull complete
2025-11-08 00:15:53.129356 | controller | ca6f5098b12e: Pull complete
2025-11-08 00:15:53.202272 | controller | 1eb3e7b2afd9: Pull complete
2025-11-08 00:15:53.270516 | controller | 18a0b750727a: Pull complete
2025-11-08 00:15:53.300878 | controller | 17f2f596716c: Pull complete
2025-11-08 00:15:53.333482 | controller | f35a9998a9bd: Pull complete
2025-11-08 00:15:53.363762 | controller | fdfc10566405: Pull complete
2025-11-08 00:15:53.395578 | controller | 1ff1c442c5fe: Pull complete
2025-11-08 00:15:55.555101 | controller | a3443c4a17ed: Pull complete
2025-11-08 00:15:57.342510 | controller | 0edcf9fb2b15: Pull complete
2025-11-08 00:16:00.376934 | controller | 5af43b69e845: Pull complete
2025-11-08 00:16:05.272169 | controller | 1651773effb2: Pull complete
2025-11-08 00:16:08.216596 | controller | 79e23c5c0fe4: Pull complete
2025-11-08 00:16:10.241394 | controller | e5058b1faaa6: Pull complete
2025-11-08 00:16:13.187067 | controller | 846775490569: Pull complete
2025-11-08 00:16:13.216331 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2025-11-08 00:16:13.219793 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2025-11-08 00:16:13.232046 | controller | quay.io/ansible/default-test-container:2.9.0
2025-11-08 00:16:13.236947 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2025-11-08 00:16:13.267267 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2025-11-08 00:16:14.772380 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2025-11-08 00:16:14.772520 | controller | 04a5f4cda3ee: Pulling fs layer
2025-11-08 00:16:14.772547 | controller | ff496a88c8ed: Pulling fs layer
2025-11-08 00:16:14.772575 | controller | 0ce83f459fe7: Pulling fs layer
2025-11-08 00:16:14.772602 | controller | 2e5170e1f099: Pulling fs layer
2025-11-08 00:16:14.772643 | controller | 7641eb41b08c: Pulling fs layer
2025-11-08 00:16:14.772657 | controller | ad15fa9da398: Pulling fs layer
2025-11-08 00:16:14.772688 | controller | 087d91352424: Pulling fs layer
2025-11-08 00:16:14.772711 | controller | 8b92efd6a100: Pulling fs layer
2025-11-08 00:16:14.772999 | controller | 7641eb41b08c: Waiting
2025-11-08 00:16:14.773032 | controller | ad15fa9da398: Waiting
2025-11-08 00:16:14.773066 | controller | 087d91352424: Waiting
2025-11-08 00:16:14.773115 | controller | 8b92efd6a100: Waiting
2025-11-08 00:16:14.773155 | controller | 2e5170e1f099: Waiting
2025-11-08 00:16:15.403390 | controller | 0ce83f459fe7: Download complete
2025-11-08 00:16:15.647592 | controller | ff496a88c8ed: Verifying Checksum
2025-11-08 00:16:15.647645 | controller | ff496a88c8ed: Download complete
2025-11-08 00:16:15.647661 | controller | 04a5f4cda3ee: Verifying Checksum
2025-11-08 00:16:15.901737 | controller | 04a5f4cda3ee: Download complete
2025-11-08 00:16:15.901808 | controller | 7641eb41b08c: Verifying Checksum
2025-11-08 00:16:16.046169 | controller | 7641eb41b08c: Download complete
2025-11-08 00:16:16.046262 | controller | ad15fa9da398: Verifying Checksum
2025-11-08 00:16:16.107561 | controller | ad15fa9da398: Download complete
2025-11-08 00:16:16.107624 | controller | 2e5170e1f099: Verifying Checksum
2025-11-08 00:16:16.441581 | controller | 2e5170e1f099: Download complete
2025-11-08 00:16:16.441679 | controller | 087d91352424: Verifying Checksum
2025-11-08 00:16:16.442241 | controller | 087d91352424: Download complete
2025-11-08 00:16:16.442264 | controller | 8b92efd6a100: Verifying Checksum
2025-11-08 00:16:16.443282 | controller | 8b92efd6a100: Download complete
2025-11-08 00:16:18.376178 | controller | 04a5f4cda3ee: Pull complete
2025-11-08 00:16:18.432656 | controller | ff496a88c8ed: Pull complete
2025-11-08 00:16:18.471260 | controller | 0ce83f459fe7: Pull complete
2025-11-08 00:16:20.094161 | controller | 2e5170e1f099: Pull complete
2025-11-08 00:16:20.121684 | controller | 7641eb41b08c: Pull complete
2025-11-08 00:16:20.144568 | controller | ad15fa9da398: Pull complete
2025-11-08 00:16:21.549160 | controller | 087d91352424: Pull complete
2025-11-08 00:16:21.583102 | controller | 8b92efd6a100: Pull complete
2025-11-08 00:16:21.625366 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2025-11-08 00:16:21.630198 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2025-11-08 00:16:21.646576 | controller | quay.io/ansible/pypi-test-container:1.0.0
2025-11-08 00:16:21.654064 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2025-11-08 00:16:32.113186 | controller | Run command: docker inspect b73cbff862b01ba93fb025e8cfd8624f1b20871c6af3ea0d12a51eaf1764af60
2025-11-08 00:16:32.190183 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2025-11-08 00:16:32.206106 | controller | Including collection: ansible.netcommon (292 files)
2025-11-08 00:16:32.220408 | controller | Including collection: ansible.network (23 files)
2025-11-08 00:16:32.220513 | controller | Including collection: ansible.utils (442 files)
2025-11-08 00:16:32.267873 | controller | Including collection: arista.eos (1022 files)
2025-11-08 00:16:32.330303 | controller | Including collection: cisco.ios (1172 files)
2025-11-08 00:16:32.386012 | controller | Including collection: cisco.iosxr (1043 files)
2025-11-08 00:16:32.480744 | controller | Including collection: cisco.nxos (1684 files)
2025-11-08 00:16:32.537847 | controller | Including collection: junipernetworks.junos (963 files)
2025-11-08 00:16:32.591762 | controller | Creating a payload archive containing 7367 files...
2025-11-08 00:16:35.097230 | controller | Created a 5982484 byte payload archive containing 7367 files in 2 seconds.
2025-11-08 00:16:35.100384 | controller | Assuming Docker is available on localhost.
2025-11-08 00:16:35.100793 | 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-11-08 00:16:35.815016 | controller | Run command: docker exec -i 63198e13d20fa2e083c9f5c37f652c0f778408158f62461defd023780f7d655c dd of=/root/docker.sh bs=65536
2025-11-08 00:16:35.924581 | controller | Run command: docker exec 63198e13d20fa2e083c9f5c37f652c0f778408158f62461defd023780f7d655c /bin/bash /root/docker.sh
2025-11-08 00:16:36.016459 | controller | Run command: docker exec -i 63198e13d20fa2e083c9f5c37f652c0f778408158f62461defd023780f7d655c dd of=/root/test.tgz bs=65536
2025-11-08 00:16:36.147365 | controller | Run command: docker exec 63198e13d20fa2e083c9f5c37f652c0f778408158f62461defd023780f7d655c tar oxzf /root/test.tgz -C /root
2025-11-08 00:16:37.204852 | controller | Run command: docker exec 63198e13d20fa2e083c9f5c37f652c0f778408158f62461defd023780f7d655c /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-omyksjmz.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2025-11-08 00:16:37.657889 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2025-11-08 00:16:37.658741 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2025-11-08 00:16:37.658914 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2025-11-08 00:16:37.661192 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2025-11-08 00:16:37.661966 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2025-11-08 00:16:37.669214 | controller | No tests applicable.
2025-11-08 00:16:37.669318 | controller | Running sanity test 'ansible-doc' with Python 3.6
2025-11-08 00:16:37.669331 | controller | No tests applicable.
2025-11-08 00:16:37.669532 | controller | Running sanity test 'changelog' with Python 3.6
2025-11-08 00:16:37.670088 | 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-11-08 00:16:38.735071 | 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-11-08 00:16:39.371536 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2025-11-08 00:16:54.392247 | controller |   WARNING: Retrying (Retry(total=4, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-11-08 00:17:09.905222 | controller |   WARNING: Retrying (Retry(total=3, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-11-08 00:17:25.922904 | controller |   WARNING: Retrying (Retry(total=2, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-11-08 00:17:42.932585 | controller |   WARNING: Retrying (Retry(total=1, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-11-08 00:18:01.954166 | controller |   WARNING: Retrying (Retry(total=0, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-11-08 00:18:16.972849 | 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-11-08 00:18:16.975698 | 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-11-08 00:18:17.165769 | 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-11-08 00:18:17.166190 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2025-11-08 00:18:17.166654 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2025-11-08 00:18:17.166874 | controller | Removing custom PyPI hosts entries: /etc/hosts
2025-11-08 00:18:17.212548 | controller | Run command: docker exec 63198e13d20fa2e083c9f5c37f652c0f778408158f62461defd023780f7d655c tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2025-11-08 00:18:17.318916 | controller | Run command: docker exec -i 63198e13d20fa2e083c9f5c37f652c0f778408158f62461defd023780f7d655c dd if=/root/output.tgz bs=65536
2025-11-08 00:18:17.414235 | controller | Run command: tar oxzf /tmp/ansible-result-zapnwtxw.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2025-11-08 00:18:17.430942 | controller | Run command: docker rm -f b73cbff862b01ba93fb025e8cfd8624f1b20871c6af3ea0d12a51eaf1764af60
2025-11-08 00:18:17.749138 | controller | Run command: docker rm -f 63198e13d20fa2e083c9f5c37f652c0f778408158f62461defd023780f7d655c
2025-11-08 00:18:18.480201 | controller | ERROR: Command "docker exec 63198e13d20fa2e083c9f5c37f652c0f778408158f62461defd023780f7d655c /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-omyksjmz.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2025-11-08 00:18:19.057741 | controller | ERROR
2025-11-08 00:18:19.058102 | controller | {
2025-11-08 00:18:19.058184 | controller |   "delta": "0:03:29.282688",
2025-11-08 00:18:19.058237 | controller |   "end": "2025-11-08 00:18:18.551439",
2025-11-08 00:18:19.058285 | controller |   "msg": "non-zero return code",
2025-11-08 00:18:19.058329 | controller |   "rc": 1,
2025-11-08 00:18:19.058374 | controller |   "start": "2025-11-08 00:14:49.268751"
2025-11-08 00:18:19.058432 | controller | }
failure
2025-11-08 00:18:19.072361 | 
2025-11-08 00:18:19.072515 | PLAY RECAP
2025-11-08 00:18:19.072584 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2025-11-08 00:18:19.072614 | 
2025-11-08 00:18:19.208674 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-11-08 00:18:19.210468 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-08 00:18:19.829113 | 
2025-11-08 00:18:19.829232 | PLAY [all]
2025-11-08 00:18:19.851282 | 
2025-11-08 00:18:19.851369 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-11-08 00:18:22.925304 | controller | changed: non-zero return code
2025-11-08 00:18:22.945411 | 
2025-11-08 00:18:22.945765 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-11-08 00:18:22.977585 | controller | skipping: Conditional result was False
2025-11-08 00:18:22.992601 | 
2025-11-08 00:18:22.992755 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-11-08 00:18:23.034360 | 
2025-11-08 00:18:23.034624 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-11-08 00:18:23.075257 | 
2025-11-08 00:18:23.075539 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-11-08 00:18:23.102581 | controller | skipping: Conditional result was False
2025-11-08 00:18:23.124153 | 
2025-11-08 00:18:23.124375 | LOOP [fetch-subunit-output : Generate subunit file]
2025-11-08 00:18:23.165177 | 
2025-11-08 00:18:23.165488 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-11-08 00:18:23.193045 | controller | skipping: Conditional result was False
2025-11-08 00:18:23.212100 | 
2025-11-08 00:18:23.212350 | TASK [fetch-subunit-output : Remove the temporary file]
2025-11-08 00:18:23.229890 | controller | skipping: Conditional result was False
2025-11-08 00:18:23.244226 | 
2025-11-08 00:18:23.244430 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-11-08 00:18:23.260570 | controller | skipping: Conditional result was False
2025-11-08 00:18:23.298042 | 
2025-11-08 00:18:23.298154 | PLAY RECAP
2025-11-08 00:18:23.298206 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-08 00:18:23.298233 | 
2025-11-08 00:18:23.410059 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-08 00:18:23.411962 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-11-08 00:18:23.983152 | 
2025-11-08 00:18:23.983265 | PLAY [all:!appliance*]
2025-11-08 00:18:24.004433 | 
2025-11-08 00:18:24.004700 | TASK [unregister the node]
2025-11-08 00:18:24.361225 | controller | skipping: Conditional result was False
2025-11-08 00:18:24.373671 | 
2025-11-08 00:18:24.373840 | TASK [include_role : fetch-output]
2025-11-08 00:18:24.422899 | controller | ok
2025-11-08 00:18:24.464877 | 
2025-11-08 00:18:24.465072 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-08 00:18:24.541335 | controller | skipping: Conditional result was False
2025-11-08 00:18:24.549491 | 
2025-11-08 00:18:24.549585 | TASK [fetch-output : Set log path for single node]
2025-11-08 00:18:24.609511 | controller | ok
2025-11-08 00:18:24.624465 | 
2025-11-08 00:18:24.624630 | LOOP [fetch-output : Ensure local output dirs]
2025-11-08 00:18:25.070716 | controller -> localhost | ok: "/var/lib/zuul/builds/9599ce379eae46d0b45cdb1b3c8b2d43/work/logs"
2025-11-08 00:18:25.325866 | controller -> localhost | changed: "/var/lib/zuul/builds/9599ce379eae46d0b45cdb1b3c8b2d43/work/artifacts"
2025-11-08 00:18:25.544697 | controller -> localhost | changed: "/var/lib/zuul/builds/9599ce379eae46d0b45cdb1b3c8b2d43/work/docs"
2025-11-08 00:18:25.569795 | 
2025-11-08 00:18:25.569989 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-08 00:18:27.861889 | controller | changed:
2025-11-08 00:18:27.862103 | controller | .d..t...... ./
2025-11-08 00:18:27.862131 | controller | cd+++++++++ controller/
2025-11-08 00:18:27.862163 | controller | changed: All items complete
2025-11-08 00:18:27.862183 | 
2025-11-08 00:18:29.906168 | controller | changed: .d..t...... ./
2025-11-08 00:18:31.986075 | controller | changed: .d..t...... ./
2025-11-08 00:18:32.017566 | 
2025-11-08 00:18:32.017778 | TASK [include_role : fetch-output-openshift]
2025-11-08 00:18:32.044811 | controller | skipping: Conditional result was False
2025-11-08 00:18:32.054759 | 
2025-11-08 00:18:32.054880 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-08 00:18:32.091794 | controller | skipping: Conditional result was False
2025-11-08 00:18:32.103680 | controller | skipping: Conditional result was False
2025-11-08 00:18:32.153453 | 
2025-11-08 00:18:32.153604 | PLAY [localhost]
2025-11-08 00:18:32.174545 | 
2025-11-08 00:18:32.174690 | TASK [Run Zuul manifest role]
2025-11-08 00:18:32.195661 | localhost | ok
2025-11-08 00:18:32.215422 | 
2025-11-08 00:18:32.215513 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-08 00:18:32.639007 | localhost | changed
2025-11-08 00:18:32.649443 | 
2025-11-08 00:18:32.649570 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-08 00:18:32.692731 | localhost | ok
2025-11-08 00:18:32.702073 | 
2025-11-08 00:18:32.702151 | TASK [Set zuul-log-path fact]
2025-11-08 00:18:32.721542 | localhost | ok
2025-11-08 00:18:32.772112 | 
2025-11-08 00:18:32.772268 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-08 00:18:32.816310 | localhost | ok
2025-11-08 00:18:32.833593 | 
2025-11-08 00:18:32.833759 | LOOP [Run upload-logs-swift role]
2025-11-08 00:18:32.869236 | localhost | Output suppressed because no_log was given
2025-11-08 00:18:32.897933 | 
2025-11-08 00:18:32.898112 | TASK [Set zuul-log-path fact]
2025-11-08 00:18:32.922576 | localhost | skipping: Conditional result was False
2025-11-08 00:18:32.928878 | 
2025-11-08 00:18:32.928957 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-11-08 00:18:33.288418 | localhost -> localhost | ok: Runtime: 0:00:00.004646
2025-11-08 00:18:33.293931 | 
2025-11-08 00:18:33.293999 | TASK [upload-logs-swift : Upload logs to swift]