2025-12-13 00:07:04.775816 | Job console starting...
2025-12-13 00:07:04.785199 | Updating repositories
2025-12-13 00:07:04.939919 | Preparing job workspace
2025-12-13 00:07:08.583941 | Running Ansible setup...
2025-12-13 00:07:16.166845 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-12-13 00:07:16.815994 | 
2025-12-13 00:07:16.816110 | PLAY [localhost]
2025-12-13 00:07:16.824433 | 
2025-12-13 00:07:16.824505 | TASK [Gathering Facts]
2025-12-13 00:07:17.843468 | localhost | ok
2025-12-13 00:07:17.857745 | 
2025-12-13 00:07:17.857836 | TASK [Setup log path fact]
2025-12-13 00:07:17.886985 | localhost | ok
2025-12-13 00:07:17.899922 | 
2025-12-13 00:07:17.900023 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-13 00:07:17.928126 | localhost | ok
2025-12-13 00:07:17.936913 | 
2025-12-13 00:07:17.936995 | TASK [emit-job-header : Print job information]
2025-12-13 00:07:17.975945 | # Job Information
2025-12-13 00:07:17.976110 | Ansible Version: 2.15.12
2025-12-13 00:07:17.976140 | Job: ansible-test-sanity-docker-stable-2.10
2025-12-13 00:07:17.976160 | Pipeline: periodic
2025-12-13 00:07:17.976179 | Executor: ze04.softwarefactory-project.io
2025-12-13 00:07:17.976196 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-12-13 00:07:17.976216 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/768/ansible/7685c89ca40e47639fdca27ab276f163/
2025-12-13 00:07:17.976235 | Event ID: 4165a8845d1e48fbbc9923de45bda5b5
2025-12-13 00:07:17.980091 | 
2025-12-13 00:07:17.980155 | LOOP [emit-job-header : Print node information]
2025-12-13 00:07:18.086724 | localhost | ok:
2025-12-13 00:07:18.087215 | localhost | # Node Information
2025-12-13 00:07:18.087279 | localhost | Inventory Hostname: controller
2025-12-13 00:07:18.087323 | localhost | Hostname: np0005557817
2025-12-13 00:07:18.087364 | localhost | Username: zuul
2025-12-13 00:07:18.087416 | localhost | Distro: Fedora 37
2025-12-13 00:07:18.087480 | localhost | Provider: ansible-vexxhost-ams1
2025-12-13 00:07:18.087521 | localhost | Region: ams1
2025-12-13 00:07:18.087560 | localhost | Label: ansible-fedora-37-1vcpu
2025-12-13 00:07:18.087598 | localhost | Product Name: OpenStack Nova
2025-12-13 00:07:18.087635 | localhost | Interface IP: 38.129.16.93
2025-12-13 00:07:18.105759 | 
2025-12-13 00:07:18.105887 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-13 00:07:18.517988 | localhost -> localhost | changed
2025-12-13 00:07:18.523492 | 
2025-12-13 00:07:18.523561 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-13 00:07:19.374653 | localhost -> localhost | changed
2025-12-13 00:07:19.403626 | 
2025-12-13 00:07:19.403736 | PLAY [all:!appliance*]
2025-12-13 00:07:19.430830 | 
2025-12-13 00:07:19.430957 | TASK [include_role : start-zuul-console]
2025-12-13 00:07:19.455165 | controller | ok
2025-12-13 00:07:19.476978 | 
2025-12-13 00:07:19.477103 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-13 00:07:20.549582 | controller | ok
2025-12-13 00:07:20.575384 | 
2025-12-13 00:07:20.575520 | TASK [use-our-mirror : Retrieve the IP address]
2025-12-13 00:07:22.646447 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-12-13 00:07:22.664540 | 
2025-12-13 00:07:22.665033 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-12-13 00:07:23.037129 | controller | skipping: Conditional result was False
2025-12-13 00:07:23.052792 | 
2025-12-13 00:07:23.052975 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-12-13 00:07:23.080717 | controller | skipping: Conditional result was False
2025-12-13 00:07:23.095785 | 
2025-12-13 00:07:23.095943 | TASK [use-our-mirror : Create the podman configuration directory]
2025-12-13 00:07:23.123365 | controller | skipping: Conditional result was False
2025-12-13 00:07:23.138232 | 
2025-12-13 00:07:23.138529 | TASK [use-our-mirror : Copy the podman configuration]
2025-12-13 00:07:23.166492 | controller | skipping: Conditional result was False
2025-12-13 00:07:23.186052 | 
2025-12-13 00:07:23.186263 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-12-13 00:07:23.212827 | controller | skipping: Conditional result was False
2025-12-13 00:07:23.222946 | 
2025-12-13 00:07:23.223074 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-12-13 00:07:23.253197 | controller | skipping: Conditional result was False
2025-12-13 00:07:23.270711 | 
2025-12-13 00:07:23.270831 | TASK [Disable Fedora Modular]
2025-12-13 00:07:24.368052 | controller | changed
2025-12-13 00:07:24.377772 | 
2025-12-13 00:07:24.377875 | TASK [Enable EPEL]
2025-12-13 00:07:24.393319 | controller | skipping: Conditional result was False
2025-12-13 00:07:24.400260 | 
2025-12-13 00:07:24.400351 | TASK [Register the RHEL node]
2025-12-13 00:07:24.792528 | 
2025-12-13 00:07:24.792706 | TASK [Show the subscription-manager status]
2025-12-13 00:07:25.158555 | controller | skipping: Conditional result was False
2025-12-13 00:07:25.172346 | 
2025-12-13 00:07:25.172485 | TASK [Enable EPEL on RHEL]
2025-12-13 00:07:25.585647 | controller | skipping: Conditional result was False
2025-12-13 00:07:25.599867 | 
2025-12-13 00:07:25.600011 | TASK [Install git and tox]
2025-12-13 00:09:57.121159 | controller | changed
2025-12-13 00:09:57.137267 | 
2025-12-13 00:09:57.137417 | TASK [include_role : prepare-workspace]
2025-12-13 00:09:57.183752 | controller | ok
2025-12-13 00:09:57.234415 | 
2025-12-13 00:09:57.234634 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-13 00:09:58.138867 | controller | ok
2025-12-13 00:09:58.147178 | 
2025-12-13 00:09:58.147280 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-13 00:10:14.364501 | controller | Output suppressed because no_log was given
2025-12-13 00:10:14.383834 | 
2025-12-13 00:10:14.384016 | TASK [include_role : prepare-workspace-openshift]
2025-12-13 00:10:14.403936 | controller | skipping: Conditional result was False
2025-12-13 00:10:14.430523 | 
2025-12-13 00:10:14.430614 | PLAY [all:!appliance]
2025-12-13 00:10:14.448721 | 
2025-12-13 00:10:14.448809 | TASK [Run add-build-sshkey role (RSA)]
2025-12-13 00:10:14.481781 | controller | ok
2025-12-13 00:10:14.499913 | 
2025-12-13 00:10:14.500025 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-13 00:10:14.780255 | controller -> localhost | ok
2025-12-13 00:10:14.796893 | 
2025-12-13 00:10:14.797053 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-13 00:10:14.846994 | controller | ok
2025-12-13 00:10:14.873653 | controller | included: /var/lib/zuul/builds/7685c89ca40e47639fdca27ab276f163/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-13 00:10:14.883926 | 
2025-12-13 00:10:14.884076 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-13 00:10:15.496347 | controller -> localhost | Generating public/private rsa key pair.
2025-12-13 00:10:15.496829 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7685c89ca40e47639fdca27ab276f163/work/7685c89ca40e47639fdca27ab276f163_id_rsa.
2025-12-13 00:10:15.496904 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7685c89ca40e47639fdca27ab276f163/work/7685c89ca40e47639fdca27ab276f163_id_rsa.pub.
2025-12-13 00:10:15.496969 | controller -> localhost | The key fingerprint is:
2025-12-13 00:10:15.497029 | controller -> localhost | SHA256:J49ikfNDMw6lrDhnjRdGxPe49UDcoAU9WKvxiEAwoDw zuul-build-sshkey
2025-12-13 00:10:15.497078 | controller -> localhost | The key's randomart image is:
2025-12-13 00:10:15.497124 | controller -> localhost | +---[RSA 2048]----+
2025-12-13 00:10:15.497169 | controller -> localhost | |..o.... .*+o     |
2025-12-13 00:10:15.497216 | controller -> localhost | |o  o .. oo=..    |
2025-12-13 00:10:15.497261 | controller -> localhost | |.E  . ..++..     |
2025-12-13 00:10:15.497306 | controller -> localhost | |  .  + =.=+      |
2025-12-13 00:10:15.497350 | controller -> localhost | |      @ Sooo     |
2025-12-13 00:10:15.497413 | controller -> localhost | |   . = O.B  .    |
2025-12-13 00:10:15.497473 | controller -> localhost | |  o = = = .      |
2025-12-13 00:10:15.497522 | controller -> localhost | |   + o . .       |
2025-12-13 00:10:15.497573 | controller -> localhost | |                 |
2025-12-13 00:10:15.497638 | controller -> localhost | +----[SHA256]-----+
2025-12-13 00:10:15.497816 | controller -> localhost | ok: Runtime: 0:00:00.137812
2025-12-13 00:10:15.516429 | 
2025-12-13 00:10:15.516595 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-13 00:10:15.560828 | controller | ok
2025-12-13 00:10:15.585307 | controller | included: /var/lib/zuul/builds/7685c89ca40e47639fdca27ab276f163/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-13 00:10:15.599124 | 
2025-12-13 00:10:15.599260 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-13 00:10:15.625771 | controller | skipping: Conditional result was False
2025-12-13 00:10:15.635302 | 
2025-12-13 00:10:15.635396 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-13 00:10:16.927837 | controller | changed
2025-12-13 00:10:16.942833 | 
2025-12-13 00:10:16.943247 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-13 00:10:17.758272 | controller | ok
2025-12-13 00:10:17.777000 | 
2025-12-13 00:10:17.777496 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-13 00:10:20.954870 | controller | changed
2025-12-13 00:10:20.970856 | 
2025-12-13 00:10:20.971334 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-13 00:10:24.069181 | controller | changed
2025-12-13 00:10:24.079847 | 
2025-12-13 00:10:24.079931 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-13 00:10:24.104794 | controller | skipping: Conditional result was False
2025-12-13 00:10:24.113290 | 
2025-12-13 00:10:24.113377 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-13 00:10:24.597041 | controller -> localhost | changed
2025-12-13 00:10:24.626483 | 
2025-12-13 00:10:24.626638 | TASK [add-build-sshkey : Add back temp key]
2025-12-13 00:10:24.999355 | controller -> localhost | Identity added: /var/lib/zuul/builds/7685c89ca40e47639fdca27ab276f163/work/7685c89ca40e47639fdca27ab276f163_id_rsa (zuul-build-sshkey)
2025-12-13 00:10:24.999593 | controller -> localhost | ok: Runtime: 0:00:00.008966
2025-12-13 00:10:25.010013 | 
2025-12-13 00:10:25.010106 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-13 00:10:25.945401 | controller | ok
2025-12-13 00:10:25.951327 | 
2025-12-13 00:10:25.951396 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-13 00:10:25.996670 | controller | skipping: Conditional result was False
2025-12-13 00:10:26.017566 | 
2025-12-13 00:10:26.017742 | TASK [Run add-build-sshkey role (ECDSA)]
2025-12-13 00:10:26.043513 | controller | ok
2025-12-13 00:10:26.068191 | 
2025-12-13 00:10:26.068326 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-13 00:10:26.307869 | controller -> localhost | ok
2025-12-13 00:10:26.318603 | 
2025-12-13 00:10:26.318748 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-13 00:10:26.343000 | controller | ok
2025-12-13 00:10:26.360659 | controller | included: /var/lib/zuul/builds/7685c89ca40e47639fdca27ab276f163/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-13 00:10:26.370523 | 
2025-12-13 00:10:26.370616 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-13 00:10:26.671568 | controller -> localhost | Generating public/private ecdsa key pair.
2025-12-13 00:10:26.671794 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7685c89ca40e47639fdca27ab276f163/work/7685c89ca40e47639fdca27ab276f163_id_ecdsa.
2025-12-13 00:10:26.671824 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7685c89ca40e47639fdca27ab276f163/work/7685c89ca40e47639fdca27ab276f163_id_ecdsa.pub.
2025-12-13 00:10:26.671852 | controller -> localhost | The key fingerprint is:
2025-12-13 00:10:26.671873 | controller -> localhost | SHA256:AQK6ybPgwyec5EqGl9oDtqdsleNmjfqfPVruRdtZIkk zuul-build-sshkey
2025-12-13 00:10:26.671891 | controller -> localhost | The key's randomart image is:
2025-12-13 00:10:26.671910 | controller -> localhost | +---[ECDSA 521]---+
2025-12-13 00:10:26.671929 | controller -> localhost | |  ... .          |
2025-12-13 00:10:26.671947 | controller -> localhost | | .   . .         |
2025-12-13 00:10:26.671964 | controller -> localhost | |.       .E       |
2025-12-13 00:10:26.671981 | controller -> localhost | |.o      ...      |
2025-12-13 00:10:26.671998 | controller -> localhost | |++  .   S+ . .   |
2025-12-13 00:10:26.672015 | controller -> localhost | |X.+=    . + +    |
2025-12-13 00:10:26.672032 | controller -> localhost | |o&*.+  . o o     |
2025-12-13 00:10:26.672049 | controller -> localhost | |=*=* .=..        |
2025-12-13 00:10:26.672066 | controller -> localhost | |++Oo.+o+.        |
2025-12-13 00:10:26.672082 | controller -> localhost | +----[SHA256]-----+
2025-12-13 00:10:26.672150 | controller -> localhost | ok: Runtime: 0:00:00.011837
2025-12-13 00:10:26.679220 | 
2025-12-13 00:10:26.679303 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-13 00:10:26.709602 | controller | ok
2025-12-13 00:10:26.717778 | controller | included: /var/lib/zuul/builds/7685c89ca40e47639fdca27ab276f163/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-13 00:10:26.727837 | 
2025-12-13 00:10:26.727985 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-13 00:10:26.752439 | controller | skipping: Conditional result was False
2025-12-13 00:10:26.792292 | 
2025-12-13 00:10:26.792414 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-13 00:10:27.860217 | controller | changed
2025-12-13 00:10:27.874555 | 
2025-12-13 00:10:27.874740 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-13 00:10:28.626459 | controller | ok
2025-12-13 00:10:28.643172 | 
2025-12-13 00:10:28.643333 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-13 00:10:31.744256 | controller | changed
2025-12-13 00:10:31.763019 | 
2025-12-13 00:10:31.763298 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-13 00:10:34.916252 | controller | changed
2025-12-13 00:10:34.922451 | 
2025-12-13 00:10:34.922533 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-13 00:10:34.949128 | controller | skipping: Conditional result was False
2025-12-13 00:10:34.956488 | 
2025-12-13 00:10:34.956593 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-13 00:10:35.257546 | controller -> localhost | changed
2025-12-13 00:10:35.291174 | 
2025-12-13 00:10:35.291409 | TASK [add-build-sshkey : Add back temp key]
2025-12-13 00:10:35.592785 | controller -> localhost | Identity added: /var/lib/zuul/builds/7685c89ca40e47639fdca27ab276f163/work/7685c89ca40e47639fdca27ab276f163_id_ecdsa (zuul-build-sshkey)
2025-12-13 00:10:35.593120 | controller -> localhost | ok: Runtime: 0:00:00.009554
2025-12-13 00:10:35.609772 | 
2025-12-13 00:10:35.609945 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-13 00:10:36.345840 | controller | ok
2025-12-13 00:10:36.358428 | 
2025-12-13 00:10:36.358567 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-13 00:10:36.396127 | controller | skipping: Conditional result was False
2025-12-13 00:10:36.425403 | 
2025-12-13 00:10:36.425574 | TASK [include_role : remove-zuul-sshkey]
2025-12-13 00:10:36.453375 | controller | skipping: Conditional result was False
2025-12-13 00:10:36.469295 | 
2025-12-13 00:10:36.469454 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-13 00:10:37.191616 | controller | ok: "logs"
2025-12-13 00:10:37.192371 | controller | ok: All items complete
2025-12-13 00:10:37.192453 | 
2025-12-13 00:10:37.877039 | controller | ok: "artifacts"
2025-12-13 00:10:38.532303 | controller | ok: "docs"
2025-12-13 00:10:38.556974 | 
2025-12-13 00:10:38.557145 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-13 00:10:39.278244 | controller | changed: "logs"
2025-12-13 00:10:39.969754 | controller | changed: "artifacts"
2025-12-13 00:10:40.612260 | controller | changed: "docs"
2025-12-13 00:10:40.667108 | 
2025-12-13 00:10:40.667275 | PLAY RECAP
2025-12-13 00:10:40.667365 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-12-13 00:10:40.667420 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-13 00:10:40.667458 | 
2025-12-13 00:10:40.783081 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-12-13 00:10:40.784064 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-13 00:10:41.378530 | 
2025-12-13 00:10:41.378643 | PLAY [all]
2025-12-13 00:10:41.405868 | 
2025-12-13 00:10:41.406005 | TASK [Install binary dependencies]
2025-12-13 00:10:41.477903 | controller | ok
2025-12-13 00:10:41.498455 | 
2025-12-13 00:10:41.498585 | TASK [bindep : Include find tasks]
2025-12-13 00:10:41.540334 | controller | ok
2025-12-13 00:10:41.548079 | controller | included: /var/lib/zuul/builds/7685c89ca40e47639fdca27ab276f163/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-12-13 00:10:41.554397 | 
2025-12-13 00:10:41.554477 | TASK [bindep : Look for bindep.txt]
2025-12-13 00:10:42.554032 | controller | ok
2025-12-13 00:10:42.567271 | 
2025-12-13 00:10:42.567421 | TASK [bindep : Define bindep_file fact]
2025-12-13 00:10:42.595191 | controller | skipping: Conditional result was False
2025-12-13 00:10:42.609841 | 
2025-12-13 00:10:42.610001 | TASK [bindep : Look for other-requirements.txt]
2025-12-13 00:10:43.234919 | controller | ok
2025-12-13 00:10:43.243791 | 
2025-12-13 00:10:43.243895 | TASK [bindep : Define bindep_file fact]
2025-12-13 00:10:43.280719 | controller | skipping: Conditional result was False
2025-12-13 00:10:43.297412 | 
2025-12-13 00:10:43.297600 | TASK [bindep : Look for bindep fallback file]
2025-12-13 00:10:43.324944 | controller | skipping: Conditional result was False
2025-12-13 00:10:43.333764 | 
2025-12-13 00:10:43.333856 | TASK [bindep : Define bindep_file fact]
2025-12-13 00:10:43.358798 | controller | skipping: Conditional result was False
2025-12-13 00:10:43.371961 | 
2025-12-13 00:10:43.372062 | TASK [bindep : Include bindep tasks]
2025-12-13 00:10:43.397992 | controller | skipping: Conditional result was False
2025-12-13 00:10:43.409248 | 
2025-12-13 00:10:43.409399 | TASK [bindep : Include install tasks]
2025-12-13 00:10:43.435569 | controller | skipping: Conditional result was False
2025-12-13 00:10:43.443936 | 
2025-12-13 00:10:43.444013 | LOOP [bindep : Include package tasks]
2025-12-13 00:10:43.510823 | 
2025-12-13 00:10:43.510982 | TASK [Run test-setup role]
2025-12-13 00:10:43.530821 | controller | ok
2025-12-13 00:10:43.548371 | 
2025-12-13 00:10:43.548463 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-12-13 00:10:44.256884 | controller | ok
2025-12-13 00:10:44.270300 | 
2025-12-13 00:10:44.270449 | TASK [test-setup : Run tools/test-setup.sh]
2025-12-13 00:10:44.647359 | controller | skipping: Conditional result was False
2025-12-13 00:10:44.673600 | 
2025-12-13 00:10:44.673709 | PLAY RECAP
2025-12-13 00:10:44.673759 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-13 00:10:44.673786 | 
2025-12-13 00:10:44.778194 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-13 00:10:44.780483 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-12-13 00:10:45.444631 | 
2025-12-13 00:10:45.444818 | PLAY [controller]
2025-12-13 00:10:45.467428 | 
2025-12-13 00:10:45.467564 | TASK [Create the /root directory]
2025-12-13 00:10:46.575886 | controller | ok
2025-12-13 00:10:46.587090 | 
2025-12-13 00:10:46.587231 | TASK [Install glibc-langpack-en]
2025-12-13 00:10:55.290334 | controller | ok: Nothing to do
2025-12-13 00:10:55.304229 | 
2025-12-13 00:10:55.304489 | TASK [Ensure controller directory exists]
2025-12-13 00:10:56.270573 | controller | changed
2025-12-13 00:10:56.283509 | 
2025-12-13 00:10:56.283647 | TASK [Install container runtime]
2025-12-13 00:10:56.364967 | controller | ok
2025-12-13 00:10:56.442657 | 
2025-12-13 00:10:56.442775 | LOOP [ensure-docker : Gather variables for each operating system]
2025-12-13 00:10:56.505870 | controller | ok: "/var/lib/zuul/builds/7685c89ca40e47639fdca27ab276f163/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-12-13 00:10:56.515864 | 
2025-12-13 00:10:56.515966 | TASK [ensure-docker : Sanity check for distro selection]
2025-12-13 00:10:56.540339 | controller | skipping: Conditional result was False
2025-12-13 00:10:56.548160 | 
2025-12-13 00:10:56.548227 | TASK [ensure-docker : Sanity check for distro install]
2025-12-13 00:10:56.582419 | controller | skipping: Conditional result was False
2025-12-13 00:10:56.589019 | 
2025-12-13 00:10:56.589085 | TASK [ensure-docker : Sanity check for upstream install]
2025-12-13 00:10:56.614404 | controller | skipping: Conditional result was False
2025-12-13 00:10:56.620648 | 
2025-12-13 00:10:56.620732 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-12-13 00:10:56.644532 | controller | skipping: Conditional result was False
2025-12-13 00:10:56.650713 | 
2025-12-13 00:10:56.650779 | TASK [ensure-docker : Include tasks for docker install]
2025-12-13 00:10:56.674033 | controller | ok
2025-12-13 00:10:56.687284 | controller | included: /var/lib/zuul/builds/7685c89ca40e47639fdca27ab276f163/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-12-13 00:10:56.695579 | 
2025-12-13 00:10:56.695643 | TASK [ensure-docker : Not Implemented]
2025-12-13 00:10:56.710838 | controller | skipping: Conditional result was False
2025-12-13 00:10:56.717233 | 
2025-12-13 00:10:56.717298 | TASK [Set up docker mirrors]
2025-12-13 00:10:56.736807 | controller | ok
2025-12-13 00:10:56.756302 | 
2025-12-13 00:10:56.756374 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-12-13 00:10:56.782848 | controller | skipping: Conditional result was False
2025-12-13 00:10:56.818273 | 
2025-12-13 00:10:56.818413 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-12-13 00:10:56.853757 | controller | skipping: Conditional result was False
2025-12-13 00:10:56.862601 | 
2025-12-13 00:10:56.862725 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-12-13 00:10:56.898439 | controller | skipping: Conditional result was False
2025-12-13 00:10:56.908064 | 
2025-12-13 00:10:56.908152 | TASK [use-docker-mirror : Set up docker mirrors]
2025-12-13 00:10:56.933281 | controller | skipping: Conditional result was False
2025-12-13 00:10:56.955265 | 
2025-12-13 00:10:56.955351 | TASK [Add all repositories]
2025-12-13 00:10:56.977946 | controller | ok
2025-12-13 00:10:57.010193 | 
2025-12-13 00:10:57.010264 | LOOP [ensure-package-repositories : Install repositories]
2025-12-13 00:10:57.053415 | controller | ok: "/var/lib/zuul/builds/7685c89ca40e47639fdca27ab276f163/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-12-13 00:10:57.054408 | controller | ok: All items complete
2025-12-13 00:10:57.054490 | 
2025-12-13 00:10:57.071979 | controller | included: /var/lib/zuul/builds/7685c89ca40e47639fdca27ab276f163/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-12-13 00:10:57.081450 | 
2025-12-13 00:10:57.081532 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-12-13 00:10:58.119652 | controller | changed
2025-12-13 00:10:58.129174 | 
2025-12-13 00:10:58.129268 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-12-13 00:11:01.623936 | controller | changed:
2025-12-13 00:11:01.624245 | controller | {
2025-12-13 00:11:01.624318 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2025-12-13 00:11:01.624376 | controller | }
2025-12-13 00:11:01.642153 | 
2025-12-13 00:11:01.642289 | LOOP [ensure-package-repositories : Import GPG keys]
2025-12-13 00:11:02.916163 | controller | changed:
2025-12-13 00:11:02.916349 | controller | {
2025-12-13 00:11:02.916378 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2025-12-13 00:11:02.916401 | controller | }
2025-12-13 00:11:02.916429 | controller | changed: All items complete
2025-12-13 00:11:02.916448 | 
2025-12-13 00:11:02.930103 | 
2025-12-13 00:11:02.930232 | TASK [ensure-package-repositories : Remove GPG temp directory]
2025-12-13 00:11:03.582592 | controller | changed
2025-12-13 00:11:03.588646 | 
2025-12-13 00:11:03.588741 | LOOP [ensure-package-repositories : Add repositories]
2025-12-13 00:11:04.477801 | controller | changed:
2025-12-13 00:11:04.488253 | controller | {
2025-12-13 00:11:04.488346 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2025-12-13 00:11:04.488391 | controller |   "description": "Docker CE Stable - $basearch",
2025-12-13 00:11:04.488429 | controller |   "gpgcheck": true,
2025-12-13 00:11:04.488466 | controller |   "name": "docker-ce-stable"
2025-12-13 00:11:04.488502 | controller | }
2025-12-13 00:11:04.524230 | 
2025-12-13 00:11:04.524435 | TASK [ensure-docker : Install pre-reqs]
2025-12-13 00:11:10.031555 | controller | ok: Nothing to do
2025-12-13 00:11:10.040126 | 
2025-12-13 00:11:10.040221 | TASK [ensure-docker : Remove packages]
2025-12-13 00:11:13.414478 | controller | ok: Nothing to do
2025-12-13 00:11:13.428022 | 
2025-12-13 00:11:13.428191 | TASK [ensure-docker : Install upstream docker using package]
2025-12-13 00:12:46.684853 | controller | changed
2025-12-13 00:12:46.694628 | 
2025-12-13 00:12:46.694778 | TASK [ensure-docker : Setup docker]
2025-12-13 00:12:46.721333 | controller | ok
2025-12-13 00:12:46.738735 | controller | included: /var/lib/zuul/builds/7685c89ca40e47639fdca27ab276f163/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2025-12-13 00:12:46.755381 | 
2025-12-13 00:12:46.755811 | TASK [ensure-docker : Ensure "docker" group exists]
2025-12-13 00:12:47.755271 | controller | ok
2025-12-13 00:12:47.762954 | 
2025-12-13 00:12:47.763069 | TASK [ensure-docker : Add user to docker group]
2025-12-13 00:12:48.948629 | controller | changed
2025-12-13 00:12:48.963036 | 
2025-12-13 00:12:48.963198 | TASK [Update docker daemon proxy configuration]
2025-12-13 00:12:48.990442 | controller | skipping: Conditional result was False
2025-12-13 00:12:49.000577 | 
2025-12-13 00:12:49.000693 | TASK [Lower default MTU]
2025-12-13 00:12:49.015793 | controller | skipping: Conditional result was False
2025-12-13 00:12:49.027990 | 
2025-12-13 00:12:49.028146 | TASK [ensure-docker : Get the status of the docket.socket unit]
2025-12-13 00:12:49.966390 | controller | inactive
2025-12-13 00:12:50.400004 | controller | ok: Runtime: 0:00:00.023391
2025-12-13 00:12:50.413666 | 
2025-12-13 00:12:50.413862 | TASK [ensure-docker : Get the status of the docket unit]
2025-12-13 00:12:51.281609 | controller | inactive
2025-12-13 00:12:51.798321 | controller | ok: Runtime: 0:00:00.031557
2025-12-13 00:12:51.810081 | 
2025-12-13 00:12:51.810230 | TASK [ensure-docker : Restart docker]
2025-12-13 00:12:51.855552 | Trigger a Docker restart
2025-12-13 00:12:51.872853 | 
2025-12-13 00:12:51.873005 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2025-12-13 00:12:51.904446 | 
2025-12-13 00:12:51.904581 | TASK [ensure-docker : Install docker-compose]
2025-12-13 00:12:51.919805 | controller | skipping: Conditional result was False
2025-12-13 00:12:51.927491 | 
2025-12-13 00:12:51.927755 | TASK [ensure-docker : Flush handlers before role exit]
2025-12-13 00:12:51.938772 | 
2025-12-13 00:12:51.938837 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2025-12-13 00:12:55.668985 | controller | changed
2025-12-13 00:12:55.680779 | 
2025-12-13 00:12:55.680928 | TASK [ensure-docker : Assure docker service is running]
2025-12-13 00:12:59.792486 | controller | changed
2025-12-13 00:12:59.804032 | 
2025-12-13 00:12:59.804156 | TASK [ensure-docker : Assure docker.socket service is running]
2025-12-13 00:13:01.227633 | controller | ok
2025-12-13 00:13:01.245036 | 
2025-12-13 00:13:01.245183 | TASK [ensure-docker : Correct group ownership on docker sock]
2025-12-13 00:13:01.939554 | controller | ok
2025-12-13 00:13:01.955336 | 
2025-12-13 00:13:01.955544 | TASK [ensure-docker : Validate ability to talk with docker]
2025-12-13 00:13:02.670917 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2025-12-13 00:13:02.828210 | controller | ok: Runtime: 0:00:00.047279
2025-12-13 00:13:02.841634 | 
2025-12-13 00:13:02.841812 | TASK [ensure-docker : Get version details]
2025-12-13 00:13:03.592464 | controller | Client: Docker Engine - Community
2025-12-13 00:13:03.592693 | controller |  Version:           24.0.7
2025-12-13 00:13:03.592739 | controller |  API version:       1.43
2025-12-13 00:13:03.592782 | controller |  Go version:        go1.20.10
2025-12-13 00:13:03.592838 | controller |  Git commit:        afdd53b
2025-12-13 00:13:03.592887 | controller |  Built:             Thu Oct 26 09:09:39 2023
2025-12-13 00:13:03.592937 | controller |  OS/Arch:           linux/amd64
2025-12-13 00:13:03.592988 | controller |  Context:           default
2025-12-13 00:13:03.593009 | controller |
2025-12-13 00:13:03.593062 | controller | Server: Docker Engine - Community
2025-12-13 00:13:03.593115 | controller |  Engine:
2025-12-13 00:13:03.593383 | controller |   Version:          24.0.7
2025-12-13 00:13:03.593429 | controller |   API version:      1.43 (minimum version 1.12)
2025-12-13 00:13:03.593470 | controller |   Go version:       go1.20.10
2025-12-13 00:13:03.593510 | controller |   Git commit:       311b9ff
2025-12-13 00:13:03.593558 | controller |   Built:            Thu Oct 26 09:08:11 2023
2025-12-13 00:13:03.593605 | controller |   OS/Arch:          linux/amd64
2025-12-13 00:13:03.593645 | controller |   Experimental:     false
2025-12-13 00:13:03.593670 | controller |  containerd:
2025-12-13 00:13:03.593785 | controller |   Version:          1.6.25
2025-12-13 00:13:03.593827 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2025-12-13 00:13:03.593852 | controller |  runc:
2025-12-13 00:13:03.593930 | controller |   Version:          1.1.10
2025-12-13 00:13:03.593990 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2025-12-13 00:13:03.594021 | controller |  docker-init:
2025-12-13 00:13:03.594099 | controller |   Version:          0.19.0
2025-12-13 00:13:03.594143 | controller |   GitCommit:        de40ad0
2025-12-13 00:13:03.748193 | controller | ok: Runtime: 0:00:00.061209
2025-12-13 00:13:03.758453 | 
2025-12-13 00:13:03.758557 | TASK [ensure-docker : Dump installed docker details]
2025-12-13 00:13:03.793787 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version:           24.0.7\n API version:       1.43\n Go version:        go1.20.10\n Git commit:        afdd53b\n Built:             Thu Oct 26 09:09:39 2023\n OS/Arch:           linux/amd64\n Context:           default\n\nServer: Docker Engine - Community\n Engine:\n  Version:          24.0.7\n  API version:      1.43 (minimum version 1.12)\n  Go version:       go1.20.10\n  Git commit:       311b9ff\n  Built:            Thu Oct 26 09:08:11 2023\n  OS/Arch:          linux/amd64\n  Experimental:     false\n containerd:\n  Version:          1.6.25\n  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n  Version:          1.1.10\n  GitCommit:        v1.1.10-0-g18a0cb0\n docker-init:\n  Version:          0.19.0\n  GitCommit:        de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2025-12-13 00:13:03.535472', 'end': '2025-12-13 00:13:03.596681', 'delta': '0:00:00.061209', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-1b38-406f-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version:           24.0.7', ' API version:       1.43', ' Go version:        go1.20.10', ' Git commit:        afdd53b', ' Built:             Thu Oct 26 09:09:39 2023', ' OS/Arch:           linux/amd64', ' Context:           default', '', 'Server: Docker Engine - Community', ' Engine:', '  Version:          24.0.7', '  API version:      1.43 (minimum version 1.12)', '  Go version:       go1.20.10', '  Git commit:       311b9ff', '  Built:            Thu Oct 26 09:08:11 2023', '  OS/Arch:          linux/amd64', '  Experimental:     false', ' containerd:', '  Version:          1.6.25', '  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', '  Version:          1.1.10', '  GitCommit:        v1.1.10-0-g18a0cb0', ' docker-init:', '  Version:          0.19.0', '  GitCommit:        de40ad0'], 'stderr_lines': [], 'failed': False}
2025-12-13 00:13:03.803468 | 
2025-12-13 00:13:03.803576 | TASK [Use buildset registry]
2025-12-13 00:13:03.822818 | controller | skipping: Conditional result was False
2025-12-13 00:13:03.849284 | 
2025-12-13 00:13:03.849491 | TASK [Ensure python3.8 is present]
2025-12-13 00:13:03.876901 | controller | skipping: Conditional result was False
2025-12-13 00:13:03.888086 | 
2025-12-13 00:13:03.888228 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-12-13 00:13:03.909325 | controller | ok
2025-12-13 00:13:03.930775 | 
2025-12-13 00:13:03.930881 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-12-13 00:13:10.496725 | controller | ok: Nothing to do
2025-12-13 00:13:10.505099 | 
2025-12-13 00:13:10.505180 | TASK [our-ensure-python : Also install python3-devel]
2025-12-13 00:13:29.758894 | controller | changed
2025-12-13 00:13:29.786448 | 
2025-12-13 00:13:29.786630 | TASK [Run ensure-virtualenv role]
2025-12-13 00:13:29.811196 | controller | ok
2025-12-13 00:13:29.845571 | 
2025-12-13 00:13:29.845746 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-12-13 00:13:30.659597 | controller | /usr/bin/virtualenv
2025-12-13 00:13:31.207366 | controller | ok: Runtime: 0:00:00.009745
2025-12-13 00:13:31.215225 | 
2025-12-13 00:13:31.215409 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-12-13 00:13:31.254071 | controller | skipping: Conditional result was False
2025-12-13 00:13:31.254544 | controller | changed: All items complete
2025-12-13 00:13:31.254591 | 
2025-12-13 00:13:31.279850 | 
2025-12-13 00:13:31.280071 | TASK [Find the full path of the Python interpreter]
2025-12-13 00:13:32.243289 | controller | /usr/bin/python3.8
2025-12-13 00:13:32.647114 | controller | ok
2025-12-13 00:13:32.655453 | 
2025-12-13 00:13:32.655551 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-12-13 00:13:35.052170 | controller | created virtual environment CPython3.8.18.final.0-64 in 1201ms
2025-12-13 00:13:35.195660 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-12-13 00:13:35.195721 | controller |   seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv)
2025-12-13 00:13:35.195737 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-12-13 00:13:35.195756 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-12-13 00:13:35.536494 | controller | changed
2025-12-13 00:13:35.544763 | 
2025-12-13 00:13:35.544853 | TASK [Set selinux package]
2025-12-13 00:13:35.569159 | controller | ok
2025-12-13 00:13:35.576237 | 
2025-12-13 00:13:35.576316 | TASK [Set selinux package (Fedora)]
2025-12-13 00:13:35.631087 | controller | ok
2025-12-13 00:13:35.637860 | 
2025-12-13 00:13:35.637937 | TASK [Install selinux into virtualenv]
2025-12-13 00:13:41.502077 | controller | Collecting selinux-please-lie-to-me
2025-12-13 00:13:41.637479 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-12-13 00:13:42.778978 | controller | Collecting setuptools<50.0.0
2025-12-13 00:13:42.787688 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-12-13 00:13:42.890126 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 8.3 MB/s eta 0:00:00
2025-12-13 00:13:43.026631 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-12-13 00:13:43.026851 | controller |   Attempting uninstall: setuptools
2025-12-13 00:13:43.028001 | controller |     Found existing installation: setuptools 62.6.0
2025-12-13 00:13:43.163550 | controller |     Uninstalling setuptools-62.6.0:
2025-12-13 00:13:43.181208 | controller |       Successfully uninstalled setuptools-62.6.0
2025-12-13 00:13:43.946798 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-12-13 00:13:44.170631 | controller |
2025-12-13 00:13:44.433786 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-12-13 00:13:44.433832 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-12-13 00:13:45.044596 | controller | ok: Runtime: 0:00:07.719788
2025-12-13 00:13:45.056266 | 
2025-12-13 00:13:45.056447 | TASK [Install pytest-forked into virtualenv]
2025-12-13 00:13:46.805431 | controller | Collecting pytest-forked
2025-12-13 00:13:46.868974 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-12-13 00:13:46.940765 | controller | Collecting py
2025-12-13 00:13:46.948944 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-12-13 00:13:46.978785 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 3.5 MB/s eta 0:00:00
2025-12-13 00:13:47.207910 | controller | Collecting pytest>=3.10
2025-12-13 00:13:47.215704 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2025-12-13 00:13:47.239602 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 16.8 MB/s eta 0:00:00
2025-12-13 00:13:47.344420 | controller | Collecting tomli>=1
2025-12-13 00:13:47.349608 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2025-12-13 00:13:47.383775 | controller | Collecting iniconfig
2025-12-13 00:13:47.388646 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-12-13 00:13:47.449009 | controller | Collecting pluggy<2,>=1.5
2025-12-13 00:13:47.454185 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-12-13 00:13:47.543789 | controller | Collecting packaging
2025-12-13 00:13:47.549670 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-12-13 00:13:47.563927 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 4.7 MB/s eta 0:00:00
2025-12-13 00:13:47.618310 | controller | Collecting exceptiongroup>=1.0.0rc8
2025-12-13 00:13:47.626346 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2025-12-13 00:13:47.724489 | controller | Collecting typing-extensions>=4.6.0
2025-12-13 00:13:47.730687 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2025-12-13 00:13:47.741455 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 4.9 MB/s eta 0:00:00
2025-12-13 00:13:47.857413 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-12-13 00:13:48.773124 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-25.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.3.0 typing-extensions-4.13.2
2025-12-13 00:13:48.779300 | controller |
2025-12-13 00:13:49.020706 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-12-13 00:13:49.020755 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-12-13 00:13:49.450789 | controller | ok: Runtime: 0:00:03.119983
2025-12-13 00:13:49.465440 | 
2025-12-13 00:13:49.465612 | TASK [Update pip]
2025-12-13 00:13:51.827104 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2025-12-13 00:13:52.326411 | controller | Collecting pip
2025-12-13 00:13:52.463785 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2025-12-13 00:13:52.612335 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 14.0 MB/s eta 0:00:00
2025-12-13 00:13:52.862416 | controller | Installing collected packages: pip
2025-12-13 00:13:52.862807 | controller |   Attempting uninstall: pip
2025-12-13 00:13:52.863900 | controller |     Found existing installation: pip 22.2.2
2025-12-13 00:13:53.443370 | controller |     Uninstalling pip-22.2.2:
2025-12-13 00:13:53.540573 | controller |       Successfully uninstalled pip-22.2.2
2025-12-13 00:13:56.131867 | controller | Successfully installed pip-25.0.1
2025-12-13 00:13:56.854307 | controller | ok: Runtime: 0:00:05.785949
2025-12-13 00:13:56.871297 | 
2025-12-13 00:13:56.871504 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-12-13 00:13:57.952086 | controller | changed
2025-12-13 00:13:57.967972 | 
2025-12-13 00:13:57.968080 | TASK [Install ansible into virtualenv]
2025-12-13 00:13:59.811115 | controller | Processing ./src/github.com/ansible/ansible
2025-12-13 00:13:59.814004 | controller |   Preparing metadata (setup.py): started
2025-12-13 00:14:00.728538 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-12-13 00:14:00.798184 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2025-12-13 00:14:00.818138 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-12-13 00:14:00.928484 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2025-12-13 00:14:00.936046 | controller |   Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.1 kB)
2025-12-13 00:14:01.512748 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2025-12-13 00:14:01.521421 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-12-13 00:14:01.554291 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (25.0)
2025-12-13 00:14:01.853955 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2025-12-13 00:14:01.866679 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2025-12-13 00:14:01.883316 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.8/site-packages (from cryptography->ansible-base==2.10.17.post0) (4.13.2)
2025-12-13 00:14:02.045348 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2025-12-13 00:14:02.053937 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2025-12-13 00:14:02.098030 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2025-12-13 00:14:02.103743 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-12-13 00:14:02.126638 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2025-12-13 00:14:02.188886 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 82.1 MB/s eta 0:00:00
2025-12-13 00:14:02.194789 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-12-13 00:14:02.212567 | controller | Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (806 kB)
2025-12-13 00:14:02.238799 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 30.7 MB/s eta 0:00:00
2025-12-13 00:14:02.244986 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2025-12-13 00:14:02.265636 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2025-12-13 00:14:02.289734 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-12-13 00:14:02.317501 | controller | Building wheels for collected packages: ansible-base
2025-12-13 00:14:02.318534 | controller |   Building wheel for ansible-base (setup.py): started
2025-12-13 00:14:05.009207 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2025-12-13 00:14:05.015221 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=ed56d92d35d78951088b84443ce5decf6f2c2b82e189358c17956e51d17cc19b
2025-12-13 00:14:05.015633 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-lcyzai04/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2025-12-13 00:14:05.036492 | controller | Successfully built ansible-base
2025-12-13 00:14:05.116026 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2025-12-13 00:14:08.985244 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-base-2.10.17.post0 cffi-1.17.1 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23
2025-12-13 00:14:09.869450 | controller | ok: Runtime: 0:00:10.283528
2025-12-13 00:14:09.876146 | 
2025-12-13 00:14:09.876210 | TASK [Setup download-artifact-fork role]
2025-12-13 00:14:09.913316 | controller | ok
2025-12-13 00:14:09.951595 | 
2025-12-13 00:14:09.951728 | TASK [download-artifact-fork : Parse inventory response]
2025-12-13 00:14:09.994271 | controller | ok
2025-12-13 00:14:10.002437 | 
2025-12-13 00:14:10.002522 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-12-13 00:14:10.027610 | controller | skipping: Conditional result was False
2025-12-13 00:14:10.038180 | 
2025-12-13 00:14:10.038296 | TASK [download-artifact-fork : Parse build response]
2025-12-13 00:14:10.074585 | controller | skipping: Conditional result was False
2025-12-13 00:14:10.083871 | 
2025-12-13 00:14:10.083955 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-12-13 00:14:10.969863 | controller | changed
2025-12-13 00:14:10.975942 | 
2025-12-13 00:14:10.976019 | LOOP [download-artifact-fork : Download archive by type]
2025-12-13 00:14:11.006035 | controller | skipping: Conditional result was False
2025-12-13 00:14:11.006473 | 
2025-12-13 00:14:13.749748 | controller | changed: OK (20188 bytes)
2025-12-13 00:14:13.767524 | 
2025-12-13 00:14:13.767645 | TASK [deploy-artifacts : define __collections]
2025-12-13 00:14:13.789579 | controller | ok
2025-12-13 00:14:13.794978 | 
2025-12-13 00:14:13.795043 | LOOP [deploy-artifacts : Create list of collections]
2025-12-13 00:14:13.818479 | controller | skipping: Conditional result was False
2025-12-13 00:14:13.825849 | controller | ok:
2025-12-13 00:14:13.825899 | controller | {
2025-12-13 00:14:13.825922 | controller |   "branch": "main",
2025-12-13 00:14:13.825940 | controller |   "job": "build-ansible-collection",
2025-12-13 00:14:13.825957 | controller |   "metadata": {
2025-12-13 00:14:13.825973 | controller |     "type": "ansible_collection",
2025-12-13 00:14:13.825988 | controller |     "version": "5.0.0"
2025-12-13 00:14:13.826004 | controller |   },
2025-12-13 00:14:13.826019 | controller |   "name": "ansible.network",
2025-12-13 00:14:13.826034 | controller |   "project": "ansible-collections/ansible.network",
2025-12-13 00:14:13.826049 | controller |   "url": "https://3a2a1ce920e108ad8a8d-7d34947d57e341adcd71d38382c1de4e.ssl.cf1.rackcdn.com/ansible/4b6cf7cc15bd442ba752c127e44fd6a1/artifacts/ansible-network-5.0.0.tar.gz"
2025-12-13 00:14:13.826071 | controller | }
2025-12-13 00:14:13.836294 | 
2025-12-13 00:14:13.836436 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-12-13 00:14:15.443356 | controller | Starting galaxy collection install process
2025-12-13 00:14:15.444444 | controller | Process install dependency map
2025-12-13 00:14:29.187788 | controller | Starting collection install process
2025-12-13 00:14:29.204900 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2025-12-13 00:14:29.205025 | controller | ansible.network (5.0.0) was installed successfully
2025-12-13 00:14:30.833622 | controller | Installing 'ansible.netcommon:8.2.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2025-12-13 00:14:30.833674 | 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-4039nsqphy0n/tmp1xhx14g8
2025-12-13 00:14:30.833702 | controller | ansible.netcommon (8.2.0) was installed successfully
2025-12-13 00:14:30.833791 | controller | Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2025-12-13 00:14:30.833987 | 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-4039nsqphy0n/tmp1xhx14g8
2025-12-13 00:14:32.581714 | controller | ansible.utils (6.0.0) was installed successfully
2025-12-13 00:14:32.581939 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2025-12-13 00:14:32.581957 | 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-4039nsqphy0n/tmp1xhx14g8
2025-12-13 00:14:35.352233 | controller | arista.eos (12.0.0) was installed successfully
2025-12-13 00:14:35.352358 | controller | Installing 'cisco.ios:11.2.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2025-12-13 00:14:35.353692 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.2.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4039nsqphy0n/tmp1xhx14g8
2025-12-13 00:14:38.442223 | controller | cisco.ios (11.2.0) was installed successfully
2025-12-13 00:14:38.442464 | controller | Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2025-12-13 00:14:38.442479 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4039nsqphy0n/tmp1xhx14g8
2025-12-13 00:14:41.453652 | controller | cisco.iosxr (12.1.1) was installed successfully
2025-12-13 00:14:41.453816 | controller | Installing 'cisco.nxos:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2025-12-13 00:14:41.454396 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4039nsqphy0n/tmp1xhx14g8
2025-12-13 00:14:45.485711 | controller | cisco.nxos (11.1.1) was installed successfully
2025-12-13 00:14:45.485876 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2025-12-13 00:14:45.486237 | 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-4039nsqphy0n/tmp1xhx14g8
2025-12-13 00:14:48.083889 | controller | junipernetworks.junos (11.0.0) was installed successfully
2025-12-13 00:14:48.751223 | controller | ok: Runtime: 0:00:33.548363
2025-12-13 00:14:48.768972 | 
2025-12-13 00:14:48.769094 | TASK [podman may need iptables kernel modules]
2025-12-13 00:14:50.129325 | controller | ok: Runtime: 0:00:00.010435
2025-12-13 00:14:50.179793 | 
2025-12-13 00:14:50.179972 | PLAY RECAP
2025-12-13 00:14:50.180045 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2025-12-13 00:14:50.180083 | 
2025-12-13 00:14:50.398658 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-12-13 00:14:50.399862 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-12-13 00:14:53.845346 | 
2025-12-13 00:14:53.845480 | PLAY [controller]
2025-12-13 00:14:53.869072 | 
2025-12-13 00:14:53.869207 | TASK [Copy the galaxy.yml on the controller]
2025-12-13 00:14:57.743017 | controller | changed
2025-12-13 00:14:57.756810 | 
2025-12-13 00:14:57.756992 | TASK [Load information from galaxy.yml]
2025-12-13 00:14:57.797941 | controller | ok
2025-12-13 00:14:57.811864 | 
2025-12-13 00:14:57.812007 | LOOP [Enable FIPS mode]
2025-12-13 00:14:57.836569 | 
2025-12-13 00:14:57.836772 | LOOP [ansible-test : Include branch specific variables.]
2025-12-13 00:14:57.877267 | controller | ok: "/var/lib/zuul/builds/7685c89ca40e47639fdca27ab276f163/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2025-12-13 00:14:57.877662 | 
2025-12-13 00:14:57.894742 | 
2025-12-13 00:14:57.894949 | TASK [ansible-test : Setup --skip-tags for test_options]
2025-12-13 00:14:57.937898 | controller | ok
2025-12-13 00:14:57.943546 | 
2025-12-13 00:14:57.943618 | TASK [ansible-test : Enable --inventory for network-integration]
2025-12-13 00:14:57.968190 | controller | skipping: Conditional result was False
2025-12-13 00:14:57.976346 | 
2025-12-13 00:14:57.976423 | TASK [ansible-test : Enable --retry-on-error]
2025-12-13 00:14:58.000980 | controller | skipping: Conditional result was False
2025-12-13 00:14:58.010733 | 
2025-12-13 00:14:58.010838 | TASK [ansible-test : Enable --continue-on-error]
2025-12-13 00:14:58.049510 | controller | ok
2025-12-13 00:14:58.060999 | 
2025-12-13 00:14:58.061096 | TASK [ansible-test : Enable --requirements]
2025-12-13 00:14:58.086886 | controller | skipping: Conditional result was False
2025-12-13 00:14:58.095945 | 
2025-12-13 00:14:58.096040 | TASK [ansible-test : Adjust options for unit tests]
2025-12-13 00:14:58.121609 | controller | skipping: Conditional result was False
2025-12-13 00:14:58.131141 | 
2025-12-13 00:14:58.131241 | TASK [ansible-test : Install requirements with unit tests]
2025-12-13 00:14:58.201009 | controller | ok
2025-12-13 00:14:58.207443 | 
2025-12-13 00:14:58.207509 | TASK [ansible-test : Set --skip-test]
2025-12-13 00:14:58.252830 | controller | skipping: Conditional result was False
2025-12-13 00:14:58.262053 | 
2025-12-13 00:14:58.262151 | TASK [ansible-test : Reduce verbosity]
2025-12-13 00:14:58.297993 | controller | ok
2025-12-13 00:14:58.309727 | 
2025-12-13 00:14:58.309857 | TASK [ansible-test : Setup --python option]
2025-12-13 00:14:58.337543 | controller | skipping: Conditional result was False
2025-12-13 00:14:58.347251 | 
2025-12-13 00:14:58.347343 | TASK [ansible-test : Setup --docker option]
2025-12-13 00:14:58.379307 | controller | ok
2025-12-13 00:14:58.386464 | 
2025-12-13 00:14:58.386548 | TASK [ansible-test : check if MANIFEST.json exists]
2025-12-13 00:14:59.256974 | controller | ok
2025-12-13 00:14:59.263187 | 
2025-12-13 00:14:59.263267 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2025-12-13 00:14:59.277563 | controller | skipping: Conditional result was False
2025-12-13 00:14:59.285274 | 
2025-12-13 00:14:59.285359 | TASK [ansible-test : Load information from MANIFEST.json]
2025-12-13 00:14:59.299808 | controller | skipping: Conditional result was False
2025-12-13 00:14:59.306990 | 
2025-12-13 00:14:59.307070 | TASK [ansible-test : Setup location of project for integration tests]
2025-12-13 00:14:59.321315 | controller | skipping: Conditional result was False
2025-12-13 00:14:59.329334 | 
2025-12-13 00:14:59.329410 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2025-12-13 00:15:00.278753 | controller | ok
2025-12-13 00:15:00.292875 | 
2025-12-13 00:15:00.293029 | TASK [ansible-test : Load information from galaxy.yml]
2025-12-13 00:15:00.333590 | controller | ok
2025-12-13 00:15:00.348507 | 
2025-12-13 00:15:00.348685 | TASK [ansible-test : Setup location of project for integration tests]
2025-12-13 00:15:00.396086 | controller | ok
2025-12-13 00:15:00.404957 | 
2025-12-13 00:15:00.405077 | TASK [ansible-test : Setup minimum test requirements]
2025-12-13 00:15:00.440278 | controller | ok
2025-12-13 00:15:00.449617 | 
2025-12-13 00:15:00.449747 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2025-12-13 00:15:01.299480 | controller | ok
2025-12-13 00:15:01.315510 | 
2025-12-13 00:15:01.315731 | TASK [ansible-test : Install python requirements]
2025-12-13 00:15:02.763544 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-12-13 00:15:02.789504 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2025-12-13 00:15:02.825350 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-12-13 00:15:02.831111 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2025-12-13 00:15:02.843924 | controller | Requirement already satisfied: pyyaml in ./venv/lib64/python3.8/site-packages (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1)) (6.0.3)
2025-12-13 00:15:02.852512 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2025-12-13 00:15:02.870944 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2025-12-13 00:15:02.953262 | controller | Installing collected packages: pathspec, yamllint
2025-12-13 00:15:03.083147 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2025-12-13 00:15:03.690746 | controller | ok: Runtime: 0:00:01.326240
2025-12-13 00:15:03.697419 | 
2025-12-13 00:15:03.697490 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2025-12-13 00:15:04.573971 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2025-12-13 00:15:05.066368 | controller | ERROR
2025-12-13 00:15:05.066880 | controller | {
2025-12-13 00:15:05.066952 | controller |   "delta": "0:00:00.009007",
2025-12-13 00:15:05.066986 | controller |   "end": "2025-12-13 00:15:04.574271",
2025-12-13 00:15:05.067013 | controller |   "msg": "non-zero return code",
2025-12-13 00:15:05.067037 | controller |   "rc": 1,
2025-12-13 00:15:05.067062 | controller |   "start": "2025-12-13 00:15:04.565264"
2025-12-13 00:15:05.067086 | controller | }
2025-12-13 00:15:05.067121 | controller | ERROR: Ignoring Errors
2025-12-13 00:15:05.077964 | 
2025-12-13 00:15:05.078124 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2025-12-13 00:15:05.446718 | controller | skipping: Conditional result was False
2025-12-13 00:15:05.453501 | 
2025-12-13 00:15:05.453601 | TASK [ansible-test : Enable persistent connection logging]
2025-12-13 00:15:06.588578 | controller | changed: section and option added
2025-12-13 00:15:06.594935 | 
2025-12-13 00:15:06.595000 | TASK [ansible-test : Set persistent ssh_type value]
2025-12-13 00:15:07.520170 | controller | changed: option added
2025-12-13 00:15:07.538658 | 
2025-12-13 00:15:07.538986 | TASK [ansible-test : Set ansible log path]
2025-12-13 00:15:08.557237 | controller | changed: section and option added
2025-12-13 00:15:08.571318 | 
2025-12-13 00:15:08.571461 | TASK [ansible-test : Identify the changed targets]
2025-12-13 00:15:08.954237 | controller | skipping: Conditional result was False
2025-12-13 00:15:08.969244 | 
2025-12-13 00:15:08.969394 | TASK [ansible-test : set_fact]
2025-12-13 00:15:09.037815 | controller | skipping: Conditional result was False
2025-12-13 00:15:09.052673 | 
2025-12-13 00:15:09.052938 | TASK [ansible-test : Set the targets]
2025-12-13 00:15:09.098631 | controller | ok
2025-12-13 00:15:09.107601 | 
2025-12-13 00:15:09.107752 | TASK [ansible-test : set_fact]
2025-12-13 00:15:09.133255 | controller | skipping: Conditional result was False
2025-12-13 00:15:09.144712 | 
2025-12-13 00:15:09.144841 | TASK [ansible-test : List the targets]
2025-12-13 00:15:09.536451 | controller | skipping: Conditional result was False
2025-12-13 00:15:09.543632 | 
2025-12-13 00:15:09.543735 | TASK [ansible-test : set_fact]
2025-12-13 00:15:09.577952 | controller | skipping: Conditional result was False
2025-12-13 00:15:09.585204 | 
2025-12-13 00:15:09.585299 | TASK [ansible-test : set_fact]
2025-12-13 00:15:09.609462 | controller | skipping: Conditional result was False
2025-12-13 00:15:09.617759 | 
2025-12-13 00:15:09.617870 | LOOP [ansible-test : set_fact]
2025-12-13 00:15:09.692466 | 
2025-12-13 00:15:09.692730 | TASK [ansible-test : set_fact]
2025-12-13 00:15:09.718037 | controller | skipping: Conditional result was False
2025-12-13 00:15:09.728982 | 
2025-12-13 00:15:09.729104 | TASK [ansible-test : debug]
2025-12-13 00:15:09.754258 | controller | skipping: Conditional result was False
2025-12-13 00:15:09.764062 | 
2025-12-13 00:15:09.764171 | TASK [ansible-test : debug]
2025-12-13 00:15:09.797095 | About to run: ansible-test sanity --requirements --docker -v
2025-12-13 00:15:09.808196 | 
2025-12-13 00:15:09.808308 | TASK [ansible-test : Run the test suite]
2025-12-13 00:15:11.829012 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2025-12-13 00:15:11.943609 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2025-12-13 00:15:13.604844 | controller | 2.9.0: Pulling from ansible/default-test-container
2025-12-13 00:15:13.605412 | controller | 171857c49d0f: Pulling fs layer
2025-12-13 00:15:13.605459 | controller | 419640447d26: Pulling fs layer
2025-12-13 00:15:13.605468 | controller | 61e52f862619: Pulling fs layer
2025-12-13 00:15:13.605475 | controller | 851de0426e26: Pulling fs layer
2025-12-13 00:15:13.605485 | controller | 37425716ea5b: Pulling fs layer
2025-12-13 00:15:13.605493 | controller | cd9b0c439616: Pulling fs layer
2025-12-13 00:15:13.605500 | controller | d4612dbd8855: Pulling fs layer
2025-12-13 00:15:13.605506 | controller | 554699364730: Pulling fs layer
2025-12-13 00:15:13.605512 | controller | 1ff15d4bdb3e: Pulling fs layer
2025-12-13 00:15:13.605519 | controller | 5f2e4724adea: Pulling fs layer
2025-12-13 00:15:13.605526 | controller | a02d9a95ccb8: Pulling fs layer
2025-12-13 00:15:13.605533 | controller | 989f4ce370cb: Pulling fs layer
2025-12-13 00:15:13.605540 | controller | 0d7f52cdf9df: Pulling fs layer
2025-12-13 00:15:13.605546 | controller | c423742351d5: Pulling fs layer
2025-12-13 00:15:13.605552 | controller | 109236341d21: Pulling fs layer
2025-12-13 00:15:13.605559 | controller | 07a2f61c96b8: Pulling fs layer
2025-12-13 00:15:13.605569 | controller | 041f122f27ae: Pulling fs layer
2025-12-13 00:15:13.605576 | controller | ca6f5098b12e: Pulling fs layer
2025-12-13 00:15:13.605582 | controller | 1eb3e7b2afd9: Pulling fs layer
2025-12-13 00:15:13.605588 | controller | 18a0b750727a: Pulling fs layer
2025-12-13 00:15:13.605594 | controller | 17f2f596716c: Pulling fs layer
2025-12-13 00:15:13.605602 | controller | f35a9998a9bd: Pulling fs layer
2025-12-13 00:15:13.605609 | controller | fdfc10566405: Pulling fs layer
2025-12-13 00:15:13.605615 | controller | 1ff1c442c5fe: Pulling fs layer
2025-12-13 00:15:13.605621 | controller | a3443c4a17ed: Pulling fs layer
2025-12-13 00:15:13.605627 | controller | 0edcf9fb2b15: Pulling fs layer
2025-12-13 00:15:13.605632 | controller | 5af43b69e845: Pulling fs layer
2025-12-13 00:15:13.605638 | controller | 1651773effb2: Pulling fs layer
2025-12-13 00:15:13.605644 | controller | 79e23c5c0fe4: Pulling fs layer
2025-12-13 00:15:13.605653 | controller | e5058b1faaa6: Pulling fs layer
2025-12-13 00:15:13.605659 | controller | 846775490569: Pulling fs layer
2025-12-13 00:15:13.605665 | controller | 851de0426e26: Waiting
2025-12-13 00:15:13.605671 | controller | 37425716ea5b: Waiting
2025-12-13 00:15:13.605677 | controller | cd9b0c439616: Waiting
2025-12-13 00:15:13.605693 | controller | d4612dbd8855: Waiting
2025-12-13 00:15:13.605699 | controller | 554699364730: Waiting
2025-12-13 00:15:13.605705 | controller | 1ff15d4bdb3e: Waiting
2025-12-13 00:15:13.605717 | controller | 5f2e4724adea: Waiting
2025-12-13 00:15:14.009858 | controller | a02d9a95ccb8: Waiting
2025-12-13 00:15:14.009901 | controller | 989f4ce370cb: Waiting
2025-12-13 00:15:14.009908 | controller | 0d7f52cdf9df: Waiting
2025-12-13 00:15:14.009914 | controller | c423742351d5: Waiting
2025-12-13 00:15:14.009921 | controller | 109236341d21: Waiting
2025-12-13 00:15:14.009927 | controller | 07a2f61c96b8: Waiting
2025-12-13 00:15:14.009933 | controller | 041f122f27ae: Waiting
2025-12-13 00:15:14.009940 | controller | ca6f5098b12e: Waiting
2025-12-13 00:15:14.009946 | controller | 1eb3e7b2afd9: Waiting
2025-12-13 00:15:14.009953 | controller | 18a0b750727a: Waiting
2025-12-13 00:15:14.009959 | controller | 17f2f596716c: Waiting
2025-12-13 00:15:14.009973 | controller | f35a9998a9bd: Waiting
2025-12-13 00:15:14.009979 | controller | fdfc10566405: Waiting
2025-12-13 00:15:14.009986 | controller | 1ff1c442c5fe: Waiting
2025-12-13 00:15:14.009992 | controller | a3443c4a17ed: Waiting
2025-12-13 00:15:14.009998 | controller | 0edcf9fb2b15: Waiting
2025-12-13 00:15:14.010004 | controller | 5af43b69e845: Waiting
2025-12-13 00:15:14.010011 | controller | 1651773effb2: Waiting
2025-12-13 00:15:14.010017 | controller | 79e23c5c0fe4: Waiting
2025-12-13 00:15:14.010023 | controller | e5058b1faaa6: Waiting
2025-12-13 00:15:14.010031 | controller | 846775490569: Waiting
2025-12-13 00:15:14.010045 | controller | 61e52f862619: Verifying Checksum
2025-12-13 00:15:14.306977 | controller | 61e52f862619: Download complete
2025-12-13 00:15:14.307034 | controller | 171857c49d0f: Verifying Checksum
2025-12-13 00:15:14.312381 | controller | 171857c49d0f: Download complete
2025-12-13 00:15:14.858232 | controller | 851de0426e26: Verifying Checksum
2025-12-13 00:15:15.675977 | controller | 851de0426e26: Download complete
2025-12-13 00:15:15.676032 | controller | cd9b0c439616: Verifying Checksum
2025-12-13 00:15:15.930891 | controller | cd9b0c439616: Download complete
2025-12-13 00:15:15.930956 | controller | 419640447d26: Verifying Checksum
2025-12-13 00:15:15.931659 | controller | 419640447d26: Download complete
2025-12-13 00:15:16.526751 | controller | d4612dbd8855: Verifying Checksum
2025-12-13 00:15:17.386706 | controller | d4612dbd8855: Download complete
2025-12-13 00:15:17.386767 | controller | 1ff15d4bdb3e: Verifying Checksum
2025-12-13 00:15:18.041342 | controller | 1ff15d4bdb3e: Download complete
2025-12-13 00:15:18.041400 | controller | 5f2e4724adea: Verifying Checksum
2025-12-13 00:15:18.041452 | controller | 5f2e4724adea: Download complete
2025-12-13 00:15:18.606893 | controller | 171857c49d0f: Pull complete
2025-12-13 00:15:18.652959 | controller | 419640447d26: Pull complete
2025-12-13 00:15:18.716572 | controller | 61e52f862619: Pull complete
2025-12-13 00:15:18.759091 | controller | 851de0426e26: Pull complete
2025-12-13 00:15:19.091872 | controller | a02d9a95ccb8: Verifying Checksum
2025-12-13 00:15:19.877099 | controller | a02d9a95ccb8: Download complete
2025-12-13 00:15:19.877173 | controller | 989f4ce370cb: Verifying Checksum
2025-12-13 00:15:19.877329 | controller | 989f4ce370cb: Download complete
2025-12-13 00:15:20.797560 | controller | 0d7f52cdf9df: Verifying Checksum
2025-12-13 00:15:20.797661 | controller | 0d7f52cdf9df: Download complete
2025-12-13 00:15:21.534294 | controller | c423742351d5: Verifying Checksum
2025-12-13 00:15:21.534408 | controller | c423742351d5: Download complete
2025-12-13 00:15:22.893544 | controller | 109236341d21: Verifying Checksum
2025-12-13 00:15:22.893719 | controller | 109236341d21: Download complete
2025-12-13 00:15:23.802357 | controller | 07a2f61c96b8: Verifying Checksum
2025-12-13 00:15:23.802450 | controller | 07a2f61c96b8: Download complete
2025-12-13 00:15:24.549894 | controller | 041f122f27ae: Verifying Checksum
2025-12-13 00:15:24.550045 | controller | 041f122f27ae: Download complete
2025-12-13 00:15:25.443376 | controller | ca6f5098b12e: Verifying Checksum
2025-12-13 00:15:25.674507 | controller | ca6f5098b12e: Download complete
2025-12-13 00:15:25.674614 | controller | 554699364730: Verifying Checksum
2025-12-13 00:15:26.221250 | controller | 554699364730: Download complete
2025-12-13 00:15:26.221320 | controller | 1eb3e7b2afd9: Verifying Checksum
2025-12-13 00:15:26.221436 | controller | 1eb3e7b2afd9: Download complete
2025-12-13 00:15:26.507902 | controller | 18a0b750727a: Verifying Checksum
2025-12-13 00:15:27.081563 | controller | 18a0b750727a: Download complete
2025-12-13 00:15:27.081680 | controller | 17f2f596716c: Verifying Checksum
2025-12-13 00:15:27.081718 | controller | 17f2f596716c: Download complete
2025-12-13 00:15:27.234519 | controller | f35a9998a9bd: Verifying Checksum
2025-12-13 00:15:27.234657 | controller | f35a9998a9bd: Download complete
2025-12-13 00:15:27.864172 | controller | fdfc10566405: Verifying Checksum
2025-12-13 00:15:27.864313 | controller | fdfc10566405: Download complete
2025-12-13 00:15:28.018108 | controller | 1ff1c442c5fe: Verifying Checksum
2025-12-13 00:15:28.018218 | controller | 1ff1c442c5fe: Download complete
2025-12-13 00:15:28.800093 | controller | a3443c4a17ed: Verifying Checksum
2025-12-13 00:15:28.800348 | controller | a3443c4a17ed: Download complete
2025-12-13 00:15:29.678175 | controller | 5af43b69e845: Verifying Checksum
2025-12-13 00:15:29.678292 | controller | 5af43b69e845: Download complete
2025-12-13 00:15:30.251182 | controller | 0edcf9fb2b15: Verifying Checksum
2025-12-13 00:15:30.251293 | controller | 0edcf9fb2b15: Download complete
2025-12-13 00:15:30.570432 | controller | 1651773effb2: Verifying Checksum
2025-12-13 00:15:30.570531 | controller | 1651773effb2: Download complete
2025-12-13 00:15:31.140325 | controller | 79e23c5c0fe4: Verifying Checksum
2025-12-13 00:15:31.140435 | controller | 79e23c5c0fe4: Download complete
2025-12-13 00:15:31.471840 | controller | e5058b1faaa6: Verifying Checksum
2025-12-13 00:15:31.471940 | controller | e5058b1faaa6: Download complete
2025-12-13 00:15:36.574190 | controller | 846775490569: Verifying Checksum
2025-12-13 00:15:45.388094 | controller | 846775490569: Download complete
2025-12-13 00:15:45.388212 | controller | 37425716ea5b: Verifying Checksum
2025-12-13 00:15:45.388349 | controller | 37425716ea5b: Download complete
2025-12-13 00:16:07.541477 | controller | 37425716ea5b: Pull complete
2025-12-13 00:16:07.570815 | controller | cd9b0c439616: Pull complete
2025-12-13 00:16:07.600324 | controller | d4612dbd8855: Pull complete
2025-12-13 00:16:13.504505 | controller | 554699364730: Pull complete
2025-12-13 00:16:13.529801 | controller | 1ff15d4bdb3e: Pull complete
2025-12-13 00:16:13.557451 | controller | 5f2e4724adea: Pull complete
2025-12-13 00:16:13.621285 | controller | a02d9a95ccb8: Pull complete
2025-12-13 00:16:13.645654 | controller | 989f4ce370cb: Pull complete
2025-12-13 00:16:13.673662 | controller | 0d7f52cdf9df: Pull complete
2025-12-13 00:16:13.716805 | controller | c423742351d5: Pull complete
2025-12-13 00:16:16.407808 | controller | 109236341d21: Pull complete
2025-12-13 00:16:16.444611 | controller | 07a2f61c96b8: Pull complete
2025-12-13 00:16:16.478765 | controller | 041f122f27ae: Pull complete
2025-12-13 00:16:16.716078 | controller | ca6f5098b12e: Pull complete
2025-12-13 00:16:16.791514 | controller | 1eb3e7b2afd9: Pull complete
2025-12-13 00:16:16.874244 | controller | 18a0b750727a: Pull complete
2025-12-13 00:16:16.914798 | controller | 17f2f596716c: Pull complete
2025-12-13 00:16:16.947354 | controller | f35a9998a9bd: Pull complete
2025-12-13 00:16:16.995526 | controller | fdfc10566405: Pull complete
2025-12-13 00:16:17.052413 | controller | 1ff1c442c5fe: Pull complete
2025-12-13 00:16:19.381713 | controller | a3443c4a17ed: Pull complete
2025-12-13 00:16:21.618064 | controller | 0edcf9fb2b15: Pull complete
2025-12-13 00:16:24.504410 | controller | 5af43b69e845: Pull complete
2025-12-13 00:16:27.525311 | controller | 1651773effb2: Pull complete
2025-12-13 00:16:30.443525 | controller | 79e23c5c0fe4: Pull complete
2025-12-13 00:16:33.564772 | controller | e5058b1faaa6: Pull complete
2025-12-13 00:16:38.425490 | controller | 846775490569: Pull complete
2025-12-13 00:16:38.445573 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2025-12-13 00:16:38.449235 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2025-12-13 00:16:38.458298 | controller | quay.io/ansible/default-test-container:2.9.0
2025-12-13 00:16:38.463748 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2025-12-13 00:16:38.495196 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2025-12-13 00:16:40.016519 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2025-12-13 00:16:40.016918 | controller | 04a5f4cda3ee: Pulling fs layer
2025-12-13 00:16:40.016950 | controller | ff496a88c8ed: Pulling fs layer
2025-12-13 00:16:40.016961 | controller | 0ce83f459fe7: Pulling fs layer
2025-12-13 00:16:40.016968 | controller | 2e5170e1f099: Pulling fs layer
2025-12-13 00:16:40.016974 | controller | 7641eb41b08c: Pulling fs layer
2025-12-13 00:16:40.016980 | controller | ad15fa9da398: Pulling fs layer
2025-12-13 00:16:40.016986 | controller | 087d91352424: Pulling fs layer
2025-12-13 00:16:40.016995 | controller | 8b92efd6a100: Pulling fs layer
2025-12-13 00:16:40.017427 | controller | ad15fa9da398: Waiting
2025-12-13 00:16:40.017449 | controller | 087d91352424: Waiting
2025-12-13 00:16:40.017575 | controller | 2e5170e1f099: Waiting
2025-12-13 00:16:40.017667 | controller | 8b92efd6a100: Waiting
2025-12-13 00:16:40.017712 | controller | 7641eb41b08c: Waiting
2025-12-13 00:16:40.415194 | controller | 0ce83f459fe7: Verifying Checksum
2025-12-13 00:16:40.448128 | controller | 0ce83f459fe7: Download complete
2025-12-13 00:16:40.448208 | controller | ff496a88c8ed: Verifying Checksum
2025-12-13 00:16:40.713383 | controller | ff496a88c8ed: Download complete
2025-12-13 00:16:40.713452 | controller | 04a5f4cda3ee: Verifying Checksum
2025-12-13 00:16:40.896630 | controller | 04a5f4cda3ee: Download complete
2025-12-13 00:16:40.896693 | controller | 7641eb41b08c: Verifying Checksum
2025-12-13 00:16:41.157840 | controller | 7641eb41b08c: Download complete
2025-12-13 00:16:41.157909 | controller | 2e5170e1f099: Verifying Checksum
2025-12-13 00:16:41.159329 | controller | 2e5170e1f099: Download complete
2025-12-13 00:16:41.271620 | controller | ad15fa9da398: Verifying Checksum
2025-12-13 00:16:41.531831 | controller | ad15fa9da398: Download complete
2025-12-13 00:16:41.531938 | controller | 087d91352424: Verifying Checksum
2025-12-13 00:16:41.594841 | controller | 087d91352424: Download complete
2025-12-13 00:16:41.594933 | controller | 8b92efd6a100: Verifying Checksum
2025-12-13 00:16:43.288294 | controller | 8b92efd6a100: Download complete
2025-12-13 00:16:43.288384 | controller | 04a5f4cda3ee: Pull complete
2025-12-13 00:16:43.354045 | controller | ff496a88c8ed: Pull complete
2025-12-13 00:16:43.416229 | controller | 0ce83f459fe7: Pull complete
2025-12-13 00:16:45.114690 | controller | 2e5170e1f099: Pull complete
2025-12-13 00:16:45.178073 | controller | 7641eb41b08c: Pull complete
2025-12-13 00:16:45.224329 | controller | ad15fa9da398: Pull complete
2025-12-13 00:16:46.587452 | controller | 087d91352424: Pull complete
2025-12-13 00:16:46.629626 | controller | 8b92efd6a100: Pull complete
2025-12-13 00:16:46.668689 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2025-12-13 00:16:46.680758 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2025-12-13 00:16:46.690381 | controller | quay.io/ansible/pypi-test-container:1.0.0
2025-12-13 00:16:46.693757 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2025-12-13 00:16:54.127755 | controller | Run command: docker inspect 5468ec7bb6c58f592863aa78669d789a7741b74e9230f22e1b3759643a9cc2e9
2025-12-13 00:16:54.199555 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2025-12-13 00:16:54.218853 | controller | Including collection: ansible.netcommon (292 files)
2025-12-13 00:16:54.218932 | controller | Including collection: ansible.network (23 files)
2025-12-13 00:16:54.233644 | controller | Including collection: ansible.utils (442 files)
2025-12-13 00:16:54.276822 | controller | Including collection: arista.eos (1022 files)
2025-12-13 00:16:54.329695 | controller | Including collection: cisco.ios (1174 files)
2025-12-13 00:16:54.386275 | controller | Including collection: cisco.iosxr (1044 files)
2025-12-13 00:16:54.470755 | controller | Including collection: cisco.nxos (1682 files)
2025-12-13 00:16:54.520432 | controller | Including collection: junipernetworks.junos (963 files)
2025-12-13 00:16:54.564525 | controller | Creating a payload archive containing 7368 files...
2025-12-13 00:16:57.045129 | controller | Created a 5992824 byte payload archive containing 7368 files in 2 seconds.
2025-12-13 00:16:57.050918 | controller | Assuming Docker is available on localhost.
2025-12-13 00:16:57.051530 | controller | Run command: docker run --detach --volume /sys/fs/cgroup:/sys/fs/cgroup:ro --privileged=false --security-opt seccomp=unconfined --volume /var/run/docker.sock:/var/run/docker.sock quay.io/ansible/default-test-container:2.9.0
2025-12-13 00:16:57.646634 | controller | Run command: docker exec -i c6fed3cab4040669bf6ea967b50a13ab38f8774875ec00a5e539256a07fe5207 dd of=/root/docker.sh bs=65536
2025-12-13 00:16:57.739097 | controller | Run command: docker exec c6fed3cab4040669bf6ea967b50a13ab38f8774875ec00a5e539256a07fe5207 /bin/bash /root/docker.sh
2025-12-13 00:16:57.835263 | controller | Run command: docker exec -i c6fed3cab4040669bf6ea967b50a13ab38f8774875ec00a5e539256a07fe5207 dd of=/root/test.tgz bs=65536
2025-12-13 00:16:57.978443 | controller | Run command: docker exec c6fed3cab4040669bf6ea967b50a13ab38f8774875ec00a5e539256a07fe5207 tar oxzf /root/test.tgz -C /root
2025-12-13 00:16:59.230254 | controller | Run command: docker exec c6fed3cab4040669bf6ea967b50a13ab38f8774875ec00a5e539256a07fe5207 /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-ponwk4zv.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2025-12-13 00:16:59.676090 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2025-12-13 00:16:59.678068 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2025-12-13 00:16:59.678515 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2025-12-13 00:16:59.680696 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2025-12-13 00:16:59.681575 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2025-12-13 00:16:59.691895 | controller | No tests applicable.
2025-12-13 00:16:59.692232 | controller | Running sanity test 'ansible-doc' with Python 3.6
2025-12-13 00:16:59.692549 | controller | No tests applicable.
2025-12-13 00:16:59.692712 | controller | Running sanity test 'changelog' with Python 3.6
2025-12-13 00:16:59.693380 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/ansible-test.txt
2025-12-13 00:17:01.004526 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt
2025-12-13 00:17:01.788182 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2025-12-13 00:17:16.810801 | controller |   WARNING: Retrying (Retry(total=4, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-12-13 00:17:32.330181 | controller |   WARNING: Retrying (Retry(total=3, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-12-13 00:17:48.349231 | controller |   WARNING: Retrying (Retry(total=2, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-12-13 00:18:05.368473 | controller |   WARNING: Retrying (Retry(total=1, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-12-13 00:18:24.393667 | controller |   WARNING: Retrying (Retry(total=0, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-12-13 00:18:39.415125 | controller |   ERROR: Could not find a version that satisfies the requirement antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2)) (from versions: none)
2025-12-13 00:18:39.416172 | controller | ERROR: No matching distribution found for antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2025-12-13 00:18:39.634708 | controller | ERROR: Command "/usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt" returned exit status 1.
2025-12-13 00:18:39.635004 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2025-12-13 00:18:39.641301 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2025-12-13 00:18:39.641694 | controller | Removing custom PyPI hosts entries: /etc/hosts
2025-12-13 00:18:39.697434 | controller | Run command: docker exec c6fed3cab4040669bf6ea967b50a13ab38f8774875ec00a5e539256a07fe5207 tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2025-12-13 00:18:39.834599 | controller | Run command: docker exec -i c6fed3cab4040669bf6ea967b50a13ab38f8774875ec00a5e539256a07fe5207 dd if=/root/output.tgz bs=65536
2025-12-13 00:18:39.948374 | controller | Run command: tar oxzf /tmp/ansible-result-bibv5qmi.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2025-12-13 00:18:39.969106 | controller | Run command: docker rm -f 5468ec7bb6c58f592863aa78669d789a7741b74e9230f22e1b3759643a9cc2e9
2025-12-13 00:18:40.302782 | controller | Run command: docker rm -f c6fed3cab4040669bf6ea967b50a13ab38f8774875ec00a5e539256a07fe5207
2025-12-13 00:18:41.146104 | controller | ERROR: Command "docker exec c6fed3cab4040669bf6ea967b50a13ab38f8774875ec00a5e539256a07fe5207 /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-ponwk4zv.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2025-12-13 00:18:41.445417 | controller | ERROR
2025-12-13 00:18:41.445830 | controller | {
2025-12-13 00:18:41.445911 | controller |   "delta": "0:03:29.957210",
2025-12-13 00:18:41.445955 | controller |   "end": "2025-12-13 00:18:41.223595",
2025-12-13 00:18:41.445994 | controller |   "msg": "non-zero return code",
2025-12-13 00:18:41.446199 | controller |   "rc": 1,
2025-12-13 00:18:41.446261 | controller |   "start": "2025-12-13 00:15:11.266385"
2025-12-13 00:18:41.446304 | controller | }
failure
2025-12-13 00:18:41.452755 | 
2025-12-13 00:18:41.453104 | PLAY RECAP
2025-12-13 00:18:41.453228 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2025-12-13 00:18:41.453279 | 
2025-12-13 00:18:41.583738 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-12-13 00:18:41.585569 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-13 00:18:42.237751 | 
2025-12-13 00:18:42.237899 | PLAY [all]
2025-12-13 00:18:42.260986 | 
2025-12-13 00:18:42.261130 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-12-13 00:18:45.326351 | controller | changed: non-zero return code
2025-12-13 00:18:45.340263 | 
2025-12-13 00:18:45.340572 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-12-13 00:18:45.368910 | controller | skipping: Conditional result was False
2025-12-13 00:18:45.384087 | 
2025-12-13 00:18:45.384289 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-12-13 00:18:45.428908 | 
2025-12-13 00:18:45.429392 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-12-13 00:18:45.471533 | 
2025-12-13 00:18:45.471874 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-12-13 00:18:45.500517 | controller | skipping: Conditional result was False
2025-12-13 00:18:45.512126 | 
2025-12-13 00:18:45.512220 | LOOP [fetch-subunit-output : Generate subunit file]
2025-12-13 00:18:45.545217 | 
2025-12-13 00:18:45.545382 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-12-13 00:18:45.570466 | controller | skipping: Conditional result was False
2025-12-13 00:18:45.581203 | 
2025-12-13 00:18:45.581295 | TASK [fetch-subunit-output : Remove the temporary file]
2025-12-13 00:18:45.606332 | controller | skipping: Conditional result was False
2025-12-13 00:18:45.616113 | 
2025-12-13 00:18:45.616208 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-12-13 00:18:45.641403 | controller | skipping: Conditional result was False
2025-12-13 00:18:45.677576 | 
2025-12-13 00:18:45.677654 | PLAY RECAP
2025-12-13 00:18:45.677740 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-13 00:18:45.677769 | 
2025-12-13 00:18:45.819370 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-13 00:18:45.820539 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-12-13 00:18:46.376820 | 
2025-12-13 00:18:46.376939 | PLAY [all:!appliance*]
2025-12-13 00:18:46.398083 | 
2025-12-13 00:18:46.398165 | TASK [unregister the node]
2025-12-13 00:18:46.758503 | controller | skipping: Conditional result was False
2025-12-13 00:18:46.772748 | 
2025-12-13 00:18:46.772929 | TASK [include_role : fetch-output]
2025-12-13 00:18:46.829175 | controller | ok
2025-12-13 00:18:46.861234 | 
2025-12-13 00:18:46.861357 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-13 00:18:46.928015 | controller | skipping: Conditional result was False
2025-12-13 00:18:46.941668 | 
2025-12-13 00:18:46.941852 | TASK [fetch-output : Set log path for single node]
2025-12-13 00:18:46.993060 | controller | ok
2025-12-13 00:18:47.009056 | 
2025-12-13 00:18:47.009290 | LOOP [fetch-output : Ensure local output dirs]
2025-12-13 00:18:47.465067 | controller -> localhost | ok: "/var/lib/zuul/builds/7685c89ca40e47639fdca27ab276f163/work/logs"
2025-12-13 00:18:47.763908 | controller -> localhost | changed: "/var/lib/zuul/builds/7685c89ca40e47639fdca27ab276f163/work/artifacts"
2025-12-13 00:18:48.059420 | controller -> localhost | changed: "/var/lib/zuul/builds/7685c89ca40e47639fdca27ab276f163/work/docs"
2025-12-13 00:18:48.079267 | 
2025-12-13 00:18:48.079462 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-13 00:18:50.377531 | controller | changed:
2025-12-13 00:18:50.378001 | controller | .d..t...... ./
2025-12-13 00:18:50.378065 | controller | cd+++++++++ controller/
2025-12-13 00:18:50.378128 | controller | changed: All items complete
2025-12-13 00:18:50.378415 | 
2025-12-13 00:18:52.466758 | controller | changed: .d..t...... ./
2025-12-13 00:18:54.508290 | controller | changed: .d..t...... ./
2025-12-13 00:18:54.526492 | 
2025-12-13 00:18:54.526658 | TASK [include_role : fetch-output-openshift]
2025-12-13 00:18:54.551392 | controller | skipping: Conditional result was False
2025-12-13 00:18:54.559013 | 
2025-12-13 00:18:54.559102 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-13 00:18:54.600122 | controller | skipping: Conditional result was False
2025-12-13 00:18:54.612736 | controller | skipping: Conditional result was False
2025-12-13 00:18:54.653741 | 
2025-12-13 00:18:54.653906 | PLAY [localhost]
2025-12-13 00:18:54.667975 | 
2025-12-13 00:18:54.668076 | TASK [Run Zuul manifest role]
2025-12-13 00:18:54.687235 | localhost | ok
2025-12-13 00:18:54.703713 | 
2025-12-13 00:18:54.703836 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-13 00:18:55.090572 | localhost | changed
2025-12-13 00:18:55.096114 | 
2025-12-13 00:18:55.096198 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-13 00:18:55.126847 | localhost | ok
2025-12-13 00:18:55.138093 | 
2025-12-13 00:18:55.138174 | TASK [Set zuul-log-path fact]
2025-12-13 00:18:55.189160 | localhost | ok
2025-12-13 00:18:55.204977 | 
2025-12-13 00:18:55.205076 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-13 00:18:55.247110 | localhost | ok
2025-12-13 00:18:55.256444 | 
2025-12-13 00:18:55.256510 | LOOP [Run upload-logs-swift role]
2025-12-13 00:18:55.293843 | localhost | Output suppressed because no_log was given
2025-12-13 00:18:55.338577 | 
2025-12-13 00:18:55.338728 | TASK [Set zuul-log-path fact]
2025-12-13 00:18:55.363983 | localhost | skipping: Conditional result was False
2025-12-13 00:18:55.371180 | 
2025-12-13 00:18:55.371288 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-12-13 00:18:55.774108 | localhost -> localhost | ok: Runtime: 0:00:00.009604
2025-12-13 00:18:55.782300 | 
2025-12-13 00:18:55.782407 | TASK [upload-logs-swift : Upload logs to swift]