2025-11-28 00:32:05.480609 | Job console starting...
2025-11-28 00:32:05.491482 | Updating repositories
2025-11-28 00:32:05.655963 | Preparing job workspace
2025-11-28 00:32:09.866327 | Running Ansible setup...
2025-11-28 00:32:17.264250 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-28 00:32:17.856777 | 
2025-11-28 00:32:17.856929 | PLAY [localhost]
2025-11-28 00:32:17.865826 | 
2025-11-28 00:32:17.865906 | TASK [Gathering Facts]
2025-11-28 00:32:18.925102 | localhost | ok
2025-11-28 00:32:18.953006 | 
2025-11-28 00:32:18.953168 | TASK [Setup log path fact]
2025-11-28 00:32:18.985558 | localhost | ok
2025-11-28 00:32:19.009455 | 
2025-11-28 00:32:19.009579 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-28 00:32:19.052801 | localhost | ok
2025-11-28 00:32:19.069169 | 
2025-11-28 00:32:19.069310 | TASK [emit-job-header : Print job information]
2025-11-28 00:32:19.123534 | # Job Information
2025-11-28 00:32:19.123779 | Ansible Version: 2.15.12
2025-11-28 00:32:19.123847 | Job: ansible-test-sanity-docker-stable-2.10
2025-11-28 00:32:19.123893 | Pipeline: periodic
2025-11-28 00:32:19.123935 | Executor: ze02.softwarefactory-project.io
2025-11-28 00:32:19.123975 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-11-28 00:32:19.124020 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/3ca/ansible/3ca1508cb9674091914af88c470dce29/
2025-11-28 00:32:19.124062 | Event ID: a5b225c8756b453caa3f2f71389276ab
2025-11-28 00:32:19.132111 | 
2025-11-28 00:32:19.132274 | LOOP [emit-job-header : Print node information]
2025-11-28 00:32:19.246179 | localhost | ok:
2025-11-28 00:32:19.246495 | localhost | # Node Information
2025-11-28 00:32:19.246557 | localhost | Inventory Hostname: controller
2025-11-28 00:32:19.246605 | localhost | Hostname: np0005538420
2025-11-28 00:32:19.246649 | localhost | Username: zuul
2025-11-28 00:32:19.246746 | localhost | Distro: Fedora 37
2025-11-28 00:32:19.246797 | localhost | Provider: ansible-vexxhost-ams1
2025-11-28 00:32:19.246838 | localhost | Region: ams1
2025-11-28 00:32:19.246879 | localhost | Label: ansible-fedora-37-1vcpu
2025-11-28 00:32:19.246922 | localhost | Product Name: OpenStack Nova
2025-11-28 00:32:19.246980 | localhost | Interface IP: 38.129.16.79
2025-11-28 00:32:19.268862 | 
2025-11-28 00:32:19.269048 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-28 00:32:19.755712 | localhost -> localhost | changed
2025-11-28 00:32:19.767254 | 
2025-11-28 00:32:19.767404 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-28 00:32:20.638730 | localhost -> localhost | changed
2025-11-28 00:32:20.661168 | 
2025-11-28 00:32:20.661323 | PLAY [all:!appliance*]
2025-11-28 00:32:20.678380 | 
2025-11-28 00:32:20.678506 | TASK [include_role : start-zuul-console]
2025-11-28 00:32:20.700247 | controller | ok
2025-11-28 00:32:20.715981 | 
2025-11-28 00:32:20.716061 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-28 00:32:21.745505 | controller | ok
2025-11-28 00:32:21.774621 | 
2025-11-28 00:32:21.774829 | TASK [use-our-mirror : Retrieve the IP address]
2025-11-28 00:32:24.024011 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-11-28 00:32:24.037552 | 
2025-11-28 00:32:24.037732 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-11-28 00:32:24.410486 | controller | skipping: Conditional result was False
2025-11-28 00:32:24.424606 | 
2025-11-28 00:32:24.424782 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-11-28 00:32:24.453456 | controller | skipping: Conditional result was False
2025-11-28 00:32:24.468721 | 
2025-11-28 00:32:24.469070 | TASK [use-our-mirror : Create the podman configuration directory]
2025-11-28 00:32:24.495901 | controller | skipping: Conditional result was False
2025-11-28 00:32:24.516432 | 
2025-11-28 00:32:24.516614 | TASK [use-our-mirror : Copy the podman configuration]
2025-11-28 00:32:24.544264 | controller | skipping: Conditional result was False
2025-11-28 00:32:24.554580 | 
2025-11-28 00:32:24.554815 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-11-28 00:32:24.583160 | controller | skipping: Conditional result was False
2025-11-28 00:32:24.595496 | 
2025-11-28 00:32:24.595642 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-11-28 00:32:24.624921 | controller | skipping: Conditional result was False
2025-11-28 00:32:24.652430 | 
2025-11-28 00:32:24.652608 | TASK [Disable Fedora Modular]
2025-11-28 00:32:25.798146 | controller | changed
2025-11-28 00:32:25.805843 | 
2025-11-28 00:32:25.805920 | TASK [Enable EPEL]
2025-11-28 00:32:25.842445 | controller | skipping: Conditional result was False
2025-11-28 00:32:25.848839 | 
2025-11-28 00:32:25.848909 | TASK [Register the RHEL node]
2025-11-28 00:32:26.264736 | 
2025-11-28 00:32:26.265125 | TASK [Show the subscription-manager status]
2025-11-28 00:32:26.641831 | controller | skipping: Conditional result was False
2025-11-28 00:32:26.656632 | 
2025-11-28 00:32:26.656833 | TASK [Enable EPEL on RHEL]
2025-11-28 00:32:27.042629 | controller | skipping: Conditional result was False
2025-11-28 00:32:27.056036 | 
2025-11-28 00:32:27.056173 | TASK [Install git and tox]
2025-11-28 00:34:21.701171 | controller | changed
2025-11-28 00:34:21.715555 | 
2025-11-28 00:34:21.715818 | TASK [include_role : prepare-workspace]
2025-11-28 00:34:21.751346 | controller | ok
2025-11-28 00:34:21.782637 | 
2025-11-28 00:34:21.782853 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-28 00:34:22.624548 | controller | ok
2025-11-28 00:34:22.638186 | 
2025-11-28 00:34:22.638321 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-28 00:34:37.952778 | controller | Output suppressed because no_log was given
2025-11-28 00:34:37.974769 | 
2025-11-28 00:34:37.974941 | TASK [include_role : prepare-workspace-openshift]
2025-11-28 00:34:38.004299 | controller | skipping: Conditional result was False
2025-11-28 00:34:38.087226 | 
2025-11-28 00:34:38.087321 | PLAY [all:!appliance]
2025-11-28 00:34:38.103290 | 
2025-11-28 00:34:38.103353 | TASK [Run add-build-sshkey role (RSA)]
2025-11-28 00:34:38.134987 | controller | ok
2025-11-28 00:34:38.150993 | 
2025-11-28 00:34:38.151061 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-28 00:34:38.441589 | controller -> localhost | ok
2025-11-28 00:34:38.456065 | 
2025-11-28 00:34:38.456230 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-28 00:34:38.498618 | controller | ok
2025-11-28 00:34:38.526772 | controller | included: /var/lib/zuul/builds/3ca1508cb9674091914af88c470dce29/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-28 00:34:38.535980 | 
2025-11-28 00:34:38.536064 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-28 00:34:39.100257 | controller -> localhost | Generating public/private rsa key pair.
2025-11-28 00:34:39.100545 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/3ca1508cb9674091914af88c470dce29/work/3ca1508cb9674091914af88c470dce29_id_rsa.
2025-11-28 00:34:39.100576 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/3ca1508cb9674091914af88c470dce29/work/3ca1508cb9674091914af88c470dce29_id_rsa.pub.
2025-11-28 00:34:39.100597 | controller -> localhost | The key fingerprint is:
2025-11-28 00:34:39.100617 | controller -> localhost | SHA256:mBgPJM09JKPuy//qdkrL/Nq4Mq7mcfd/jcUw8AcqenQ zuul-build-sshkey
2025-11-28 00:34:39.100635 | controller -> localhost | The key's randomart image is:
2025-11-28 00:34:39.100654 | controller -> localhost | +---[RSA 2048]----+
2025-11-28 00:34:39.100692 | controller -> localhost | |  .o+o.          |
2025-11-28 00:34:39.100713 | controller -> localhost | |   +ooo  . .     |
2025-11-28 00:34:39.100731 | controller -> localhost | |  . o  .  + .    |
2025-11-28 00:34:39.100749 | controller -> localhost | | .   = = E + .   |
2025-11-28 00:34:39.100767 | controller -> localhost | |  . . * S   =    |
2025-11-28 00:34:39.100799 | controller -> localhost | | .   . .     o   |
2025-11-28 00:34:39.100824 | controller -> localhost | | ......     +    |
2025-11-28 00:34:39.100843 | controller -> localhost | | o==o+o    o .   |
2025-11-28 00:34:39.100861 | controller -> localhost | |+++*%O+....      |
2025-11-28 00:34:39.100881 | controller -> localhost | +----[SHA256]-----+
2025-11-28 00:34:39.100947 | controller -> localhost | ok: Runtime: 0:00:00.117238
2025-11-28 00:34:39.108349 | 
2025-11-28 00:34:39.108416 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-28 00:34:39.139891 | controller | ok
2025-11-28 00:34:39.150135 | controller | included: /var/lib/zuul/builds/3ca1508cb9674091914af88c470dce29/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-28 00:34:39.158836 | 
2025-11-28 00:34:39.158898 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-28 00:34:39.184265 | controller | skipping: Conditional result was False
2025-11-28 00:34:39.191435 | 
2025-11-28 00:34:39.191500 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-28 00:34:40.248504 | controller | changed
2025-11-28 00:34:40.261931 | 
2025-11-28 00:34:40.262078 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-28 00:34:40.918416 | controller | ok
2025-11-28 00:34:40.930772 | 
2025-11-28 00:34:40.930908 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-28 00:34:43.961314 | controller | changed
2025-11-28 00:34:43.974166 | 
2025-11-28 00:34:43.974310 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-28 00:34:46.973542 | controller | changed
2025-11-28 00:34:46.987249 | 
2025-11-28 00:34:46.987404 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-28 00:34:47.017410 | controller | skipping: Conditional result was False
2025-11-28 00:34:47.034329 | 
2025-11-28 00:34:47.034557 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-28 00:34:47.442166 | controller -> localhost | changed
2025-11-28 00:34:47.467779 | 
2025-11-28 00:34:47.467991 | TASK [add-build-sshkey : Add back temp key]
2025-11-28 00:34:47.776529 | controller -> localhost | Identity added: /var/lib/zuul/builds/3ca1508cb9674091914af88c470dce29/work/3ca1508cb9674091914af88c470dce29_id_rsa (zuul-build-sshkey)
2025-11-28 00:34:47.777114 | controller -> localhost | ok: Runtime: 0:00:00.010767
2025-11-28 00:34:47.790493 | 
2025-11-28 00:34:47.790656 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-28 00:34:48.766453 | controller | ok
2025-11-28 00:34:48.774742 | 
2025-11-28 00:34:48.774919 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-28 00:34:48.816396 | controller | skipping: Conditional result was False
2025-11-28 00:34:48.835563 | 
2025-11-28 00:34:48.835713 | TASK [Run add-build-sshkey role (ECDSA)]
2025-11-28 00:34:48.873395 | controller | ok
2025-11-28 00:34:48.897461 | 
2025-11-28 00:34:48.897567 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-28 00:34:49.201321 | controller -> localhost | ok
2025-11-28 00:34:49.216398 | 
2025-11-28 00:34:49.216588 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-28 00:34:49.249750 | controller | ok
2025-11-28 00:34:49.275097 | controller | included: /var/lib/zuul/builds/3ca1508cb9674091914af88c470dce29/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-28 00:34:49.285252 | 
2025-11-28 00:34:49.285338 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-28 00:34:49.596885 | controller -> localhost | Generating public/private ecdsa key pair.
2025-11-28 00:34:49.597217 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/3ca1508cb9674091914af88c470dce29/work/3ca1508cb9674091914af88c470dce29_id_ecdsa.
2025-11-28 00:34:49.597277 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/3ca1508cb9674091914af88c470dce29/work/3ca1508cb9674091914af88c470dce29_id_ecdsa.pub.
2025-11-28 00:34:49.597342 | controller -> localhost | The key fingerprint is:
2025-11-28 00:34:49.597387 | controller -> localhost | SHA256:hfSfoCXwvOPzcOHWyvg/vxKPxeyPckr6ErfKcTgokx0 zuul-build-sshkey
2025-11-28 00:34:49.597432 | controller -> localhost | The key's randomart image is:
2025-11-28 00:34:49.597471 | controller -> localhost | +---[ECDSA 521]---+
2025-11-28 00:34:49.597512 | controller -> localhost | |      . .        |
2025-11-28 00:34:49.597558 | controller -> localhost | |       = o       |
2025-11-28 00:34:49.597616 | controller -> localhost | |        = =      |
2025-11-28 00:34:49.597694 | controller -> localhost | |         * o .   |
2025-11-28 00:34:49.597749 | controller -> localhost | |        E . oo   |
2025-11-28 00:34:49.597791 | controller -> localhost | |       + =.+o +  |
2025-11-28 00:34:49.597830 | controller -> localhost | |      + * Bo+B   |
2025-11-28 00:34:49.597869 | controller -> localhost | |       o X.B* +. |
2025-11-28 00:34:49.597908 | controller -> localhost | |        ..O=+B+o.|
2025-11-28 00:34:49.597947 | controller -> localhost | +----[SHA256]-----+
2025-11-28 00:34:49.598043 | controller -> localhost | ok: Runtime: 0:00:00.015808
2025-11-28 00:34:49.612015 | 
2025-11-28 00:34:49.612154 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-28 00:34:49.645481 | controller | ok
2025-11-28 00:34:49.663112 | controller | included: /var/lib/zuul/builds/3ca1508cb9674091914af88c470dce29/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-28 00:34:49.684473 | 
2025-11-28 00:34:49.684561 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-28 00:34:49.711537 | controller | skipping: Conditional result was False
2025-11-28 00:34:49.720166 | 
2025-11-28 00:34:49.720257 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-28 00:34:50.647529 | controller | changed
2025-11-28 00:34:50.660810 | 
2025-11-28 00:34:50.660984 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-28 00:34:51.400154 | controller | ok
2025-11-28 00:34:51.408179 | 
2025-11-28 00:34:51.408265 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-28 00:34:54.475538 | controller | changed
2025-11-28 00:34:54.494078 | 
2025-11-28 00:34:54.494322 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-28 00:34:57.520184 | controller | changed
2025-11-28 00:34:57.534476 | 
2025-11-28 00:34:57.534629 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-28 00:34:57.564274 | controller | skipping: Conditional result was False
2025-11-28 00:34:57.581997 | 
2025-11-28 00:34:57.582180 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-28 00:34:57.880004 | controller -> localhost | changed
2025-11-28 00:34:57.898063 | 
2025-11-28 00:34:57.898207 | TASK [add-build-sshkey : Add back temp key]
2025-11-28 00:34:58.198248 | controller -> localhost | Identity added: /var/lib/zuul/builds/3ca1508cb9674091914af88c470dce29/work/3ca1508cb9674091914af88c470dce29_id_ecdsa (zuul-build-sshkey)
2025-11-28 00:34:58.198746 | controller -> localhost | ok: Runtime: 0:00:00.018157
2025-11-28 00:34:58.213368 | 
2025-11-28 00:34:58.213563 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-28 00:34:58.940755 | controller | ok
2025-11-28 00:34:58.953449 | 
2025-11-28 00:34:58.953598 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-28 00:34:59.003182 | controller | skipping: Conditional result was False
2025-11-28 00:34:59.029333 | 
2025-11-28 00:34:59.029612 | TASK [include_role : remove-zuul-sshkey]
2025-11-28 00:34:59.058777 | controller | skipping: Conditional result was False
2025-11-28 00:34:59.078569 | 
2025-11-28 00:34:59.078766 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-28 00:34:59.757454 | controller | ok: "logs"
2025-11-28 00:34:59.758161 | controller | ok: All items complete
2025-11-28 00:34:59.758250 | 
2025-11-28 00:35:00.391090 | controller | ok: "artifacts"
2025-11-28 00:35:01.002361 | controller | ok: "docs"
2025-11-28 00:35:01.021617 | 
2025-11-28 00:35:01.021833 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-28 00:35:01.692033 | controller | changed: "logs"
2025-11-28 00:35:02.310855 | controller | changed: "artifacts"
2025-11-28 00:35:02.936341 | controller | changed: "docs"
2025-11-28 00:35:02.997522 | 
2025-11-28 00:35:02.997715 | PLAY RECAP
2025-11-28 00:35:02.997790 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-11-28 00:35:02.997827 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-28 00:35:02.997851 | 
2025-11-28 00:35:03.155038 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-28 00:35:03.156137 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-28 00:35:03.702881 | 
2025-11-28 00:35:03.703013 | PLAY [all]
2025-11-28 00:35:03.725583 | 
2025-11-28 00:35:03.725772 | TASK [Install binary dependencies]
2025-11-28 00:35:03.796880 | controller | ok
2025-11-28 00:35:03.816196 | 
2025-11-28 00:35:03.816294 | TASK [bindep : Include find tasks]
2025-11-28 00:35:03.866439 | controller | ok
2025-11-28 00:35:03.874731 | controller | included: /var/lib/zuul/builds/3ca1508cb9674091914af88c470dce29/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-11-28 00:35:03.881203 | 
2025-11-28 00:35:03.881300 | TASK [bindep : Look for bindep.txt]
2025-11-28 00:35:04.830466 | controller | ok
2025-11-28 00:35:04.839546 | 
2025-11-28 00:35:04.839648 | TASK [bindep : Define bindep_file fact]
2025-11-28 00:35:04.866529 | controller | skipping: Conditional result was False
2025-11-28 00:35:04.877808 | 
2025-11-28 00:35:04.877914 | TASK [bindep : Look for other-requirements.txt]
2025-11-28 00:35:05.503431 | controller | ok
2025-11-28 00:35:05.509876 | 
2025-11-28 00:35:05.509951 | TASK [bindep : Define bindep_file fact]
2025-11-28 00:35:05.545311 | controller | skipping: Conditional result was False
2025-11-28 00:35:05.551560 | 
2025-11-28 00:35:05.551625 | TASK [bindep : Look for bindep fallback file]
2025-11-28 00:35:05.586514 | controller | skipping: Conditional result was False
2025-11-28 00:35:05.593817 | 
2025-11-28 00:35:05.593895 | TASK [bindep : Define bindep_file fact]
2025-11-28 00:35:05.619387 | controller | skipping: Conditional result was False
2025-11-28 00:35:05.625975 | 
2025-11-28 00:35:05.626040 | TASK [bindep : Include bindep tasks]
2025-11-28 00:35:05.651055 | controller | skipping: Conditional result was False
2025-11-28 00:35:05.657651 | 
2025-11-28 00:35:05.657845 | TASK [bindep : Include install tasks]
2025-11-28 00:35:05.682465 | controller | skipping: Conditional result was False
2025-11-28 00:35:05.689166 | 
2025-11-28 00:35:05.689231 | LOOP [bindep : Include package tasks]
2025-11-28 00:35:05.762480 | 
2025-11-28 00:35:05.762769 | TASK [Run test-setup role]
2025-11-28 00:35:05.786296 | controller | ok
2025-11-28 00:35:05.811235 | 
2025-11-28 00:35:05.811331 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-11-28 00:35:06.525992 | controller | ok
2025-11-28 00:35:06.537972 | 
2025-11-28 00:35:06.538070 | TASK [test-setup : Run tools/test-setup.sh]
2025-11-28 00:35:06.902706 | controller | skipping: Conditional result was False
2025-11-28 00:35:06.930294 | 
2025-11-28 00:35:06.930368 | PLAY RECAP
2025-11-28 00:35:06.930409 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-28 00:35:06.930429 | 
2025-11-28 00:35:07.043712 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-28 00:35:07.045518 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-28 00:35:07.712374 | 
2025-11-28 00:35:07.712565 | PLAY [controller]
2025-11-28 00:35:07.734120 | 
2025-11-28 00:35:07.734222 | TASK [Create the /root directory]
2025-11-28 00:35:08.769571 | controller | ok
2025-11-28 00:35:08.783249 | 
2025-11-28 00:35:08.783471 | TASK [Install glibc-langpack-en]
2025-11-28 00:35:16.017073 | controller | ok: Nothing to do
2025-11-28 00:35:16.030997 | 
2025-11-28 00:35:16.031139 | TASK [Ensure controller directory exists]
2025-11-28 00:35:16.855189 | controller | changed
2025-11-28 00:35:16.867239 | 
2025-11-28 00:35:16.867376 | TASK [Install container runtime]
2025-11-28 00:35:16.948086 | controller | ok
2025-11-28 00:35:17.014134 | 
2025-11-28 00:35:17.014282 | LOOP [ensure-docker : Gather variables for each operating system]
2025-11-28 00:35:17.077115 | controller | ok: "/var/lib/zuul/builds/3ca1508cb9674091914af88c470dce29/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-11-28 00:35:17.097084 | 
2025-11-28 00:35:17.097251 | TASK [ensure-docker : Sanity check for distro selection]
2025-11-28 00:35:17.125244 | controller | skipping: Conditional result was False
2025-11-28 00:35:17.137850 | 
2025-11-28 00:35:17.137989 | TASK [ensure-docker : Sanity check for distro install]
2025-11-28 00:35:17.176201 | controller | skipping: Conditional result was False
2025-11-28 00:35:17.190238 | 
2025-11-28 00:35:17.190403 | TASK [ensure-docker : Sanity check for upstream install]
2025-11-28 00:35:17.230729 | controller | skipping: Conditional result was False
2025-11-28 00:35:17.245946 | 
2025-11-28 00:35:17.246094 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-11-28 00:35:17.273996 | controller | skipping: Conditional result was False
2025-11-28 00:35:17.287520 | 
2025-11-28 00:35:17.287719 | TASK [ensure-docker : Include tasks for docker install]
2025-11-28 00:35:17.328086 | controller | ok
2025-11-28 00:35:17.346201 | controller | included: /var/lib/zuul/builds/3ca1508cb9674091914af88c470dce29/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-11-28 00:35:17.357973 | 
2025-11-28 00:35:17.358060 | TASK [ensure-docker : Not Implemented]
2025-11-28 00:35:17.383914 | controller | skipping: Conditional result was False
2025-11-28 00:35:17.392550 | 
2025-11-28 00:35:17.392636 | TASK [Set up docker mirrors]
2025-11-28 00:35:17.416933 | controller | ok
2025-11-28 00:35:17.446144 | 
2025-11-28 00:35:17.446247 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-11-28 00:35:17.493234 | controller | skipping: Conditional result was False
2025-11-28 00:35:17.508526 | 
2025-11-28 00:35:17.508676 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-11-28 00:35:17.545865 | controller | skipping: Conditional result was False
2025-11-28 00:35:17.552841 | 
2025-11-28 00:35:17.552906 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-11-28 00:35:17.599803 | controller | skipping: Conditional result was False
2025-11-28 00:35:17.609656 | 
2025-11-28 00:35:17.609924 | TASK [use-docker-mirror : Set up docker mirrors]
2025-11-28 00:35:17.689130 | controller | skipping: Conditional result was False
2025-11-28 00:35:17.758458 | 
2025-11-28 00:35:17.758630 | TASK [Add all repositories]
2025-11-28 00:35:17.796321 | controller | ok
2025-11-28 00:35:17.840967 | 
2025-11-28 00:35:17.841071 | LOOP [ensure-package-repositories : Install repositories]
2025-11-28 00:35:17.887474 | controller | ok: "/var/lib/zuul/builds/3ca1508cb9674091914af88c470dce29/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-11-28 00:35:17.887978 | controller | ok: All items complete
2025-11-28 00:35:17.888049 | 
2025-11-28 00:35:17.909980 | controller | included: /var/lib/zuul/builds/3ca1508cb9674091914af88c470dce29/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-11-28 00:35:17.918624 | 
2025-11-28 00:35:17.918726 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-11-28 00:35:18.895027 | controller | changed
2025-11-28 00:35:18.908728 | 
2025-11-28 00:35:18.908875 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-11-28 00:35:22.108285 | controller | changed:
2025-11-28 00:35:22.108489 | controller | {
2025-11-28 00:35:22.108515 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2025-11-28 00:35:22.108538 | controller | }
2025-11-28 00:35:22.115715 | 
2025-11-28 00:35:22.115785 | LOOP [ensure-package-repositories : Import GPG keys]
2025-11-28 00:35:23.349021 | controller | changed:
2025-11-28 00:35:23.349414 | controller | {
2025-11-28 00:35:23.349480 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2025-11-28 00:35:23.349534 | controller | }
2025-11-28 00:35:23.349604 | controller | changed: All items complete
2025-11-28 00:35:23.349651 | 
2025-11-28 00:35:23.363963 | 
2025-11-28 00:35:23.364096 | TASK [ensure-package-repositories : Remove GPG temp directory]
2025-11-28 00:35:24.068989 | controller | changed
2025-11-28 00:35:24.082505 | 
2025-11-28 00:35:24.082648 | LOOP [ensure-package-repositories : Add repositories]
2025-11-28 00:35:24.944908 | controller | changed:
2025-11-28 00:35:24.945260 | controller | {
2025-11-28 00:35:24.945316 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2025-11-28 00:35:24.945359 | controller |   "description": "Docker CE Stable - $basearch",
2025-11-28 00:35:24.945397 | controller |   "gpgcheck": true,
2025-11-28 00:35:24.945434 | controller |   "name": "docker-ce-stable"
2025-11-28 00:35:24.945469 | controller | }
2025-11-28 00:35:24.975424 | 
2025-11-28 00:35:24.975592 | TASK [ensure-docker : Install pre-reqs]
2025-11-28 00:35:29.928202 | controller | ok: Nothing to do
2025-11-28 00:35:29.940601 | 
2025-11-28 00:35:29.940823 | TASK [ensure-docker : Remove packages]
2025-11-28 00:35:33.037931 | controller | ok: Nothing to do
2025-11-28 00:35:33.050731 | 
2025-11-28 00:35:33.050869 | TASK [ensure-docker : Install upstream docker using package]
2025-11-28 00:36:52.012637 | controller | changed
2025-11-28 00:36:52.026593 | 
2025-11-28 00:36:52.026999 | TASK [ensure-docker : Setup docker]
2025-11-28 00:36:52.061533 | controller | ok
2025-11-28 00:36:52.082875 | controller | included: /var/lib/zuul/builds/3ca1508cb9674091914af88c470dce29/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2025-11-28 00:36:52.099511 | 
2025-11-28 00:36:52.099596 | TASK [ensure-docker : Ensure "docker" group exists]
2025-11-28 00:36:53.095767 | controller | ok
2025-11-28 00:36:53.108881 | 
2025-11-28 00:36:53.109037 | TASK [ensure-docker : Add user to docker group]
2025-11-28 00:36:54.124722 | controller | changed
2025-11-28 00:36:54.137056 | 
2025-11-28 00:36:54.137179 | TASK [Update docker daemon proxy configuration]
2025-11-28 00:36:54.174938 | controller | skipping: Conditional result was False
2025-11-28 00:36:54.188848 | 
2025-11-28 00:36:54.188999 | TASK [Lower default MTU]
2025-11-28 00:36:54.215884 | controller | skipping: Conditional result was False
2025-11-28 00:36:54.230373 | 
2025-11-28 00:36:54.230503 | TASK [ensure-docker : Get the status of the docket.socket unit]
2025-11-28 00:36:54.984817 | controller | inactive
2025-11-28 00:36:55.114447 | controller | ok: Runtime: 0:00:00.022417
2025-11-28 00:36:55.128892 | 
2025-11-28 00:36:55.129066 | TASK [ensure-docker : Get the status of the docket unit]
2025-11-28 00:36:55.799343 | controller | inactive
2025-11-28 00:36:56.004615 | controller | ok: Runtime: 0:00:00.020400
2025-11-28 00:36:56.019819 | 
2025-11-28 00:36:56.019979 | TASK [ensure-docker : Restart docker]
2025-11-28 00:36:56.080452 | Trigger a Docker restart
2025-11-28 00:36:56.092799 | 
2025-11-28 00:36:56.092951 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2025-11-28 00:36:56.130947 | 
2025-11-28 00:36:56.131092 | TASK [ensure-docker : Install docker-compose]
2025-11-28 00:36:56.156779 | controller | skipping: Conditional result was False
2025-11-28 00:36:56.166847 | 
2025-11-28 00:36:56.166948 | TASK [ensure-docker : Flush handlers before role exit]
2025-11-28 00:36:56.181236 | 
2025-11-28 00:36:56.181336 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2025-11-28 00:36:59.363839 | controller | changed
2025-11-28 00:36:59.379915 | 
2025-11-28 00:36:59.380059 | TASK [ensure-docker : Assure docker service is running]
2025-11-28 00:37:02.767827 | controller | changed
2025-11-28 00:37:02.786072 | 
2025-11-28 00:37:02.786209 | TASK [ensure-docker : Assure docker.socket service is running]
2025-11-28 00:37:04.779277 | controller | ok
2025-11-28 00:37:04.803516 | 
2025-11-28 00:37:04.803768 | TASK [ensure-docker : Correct group ownership on docker sock]
2025-11-28 00:37:05.750828 | controller | ok
2025-11-28 00:37:05.774994 | 
2025-11-28 00:37:05.775099 | TASK [ensure-docker : Validate ability to talk with docker]
2025-11-28 00:37:06.464939 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2025-11-28 00:37:06.674373 | controller | ok: Runtime: 0:00:00.043738
2025-11-28 00:37:06.687578 | 
2025-11-28 00:37:06.687757 | TASK [ensure-docker : Get version details]
2025-11-28 00:37:07.372638 | controller | Client: Docker Engine - Community
2025-11-28 00:37:07.372759 | controller |  Version:           24.0.7
2025-11-28 00:37:07.372770 | controller |  API version:       1.43
2025-11-28 00:37:07.372778 | controller |  Go version:        go1.20.10
2025-11-28 00:37:07.372803 | controller |  Git commit:        afdd53b
2025-11-28 00:37:07.372811 | controller |  Built:             Thu Oct 26 09:09:39 2023
2025-11-28 00:37:07.372822 | controller |  OS/Arch:           linux/amd64
2025-11-28 00:37:07.372833 | controller |  Context:           default
2025-11-28 00:37:07.372846 | controller |
2025-11-28 00:37:07.372868 | controller | Server: Docker Engine - Community
2025-11-28 00:37:07.378338 | controller |  Engine:
2025-11-28 00:37:07.378412 | controller |   Version:          24.0.7
2025-11-28 00:37:07.378420 | controller |   API version:      1.43 (minimum version 1.12)
2025-11-28 00:37:07.378427 | controller |   Go version:       go1.20.10
2025-11-28 00:37:07.378434 | controller |   Git commit:       311b9ff
2025-11-28 00:37:07.378442 | controller |   Built:            Thu Oct 26 09:08:11 2023
2025-11-28 00:37:07.378449 | controller |   OS/Arch:          linux/amd64
2025-11-28 00:37:07.378456 | controller |   Experimental:     false
2025-11-28 00:37:07.378462 | controller |  containerd:
2025-11-28 00:37:07.378468 | controller |   Version:          1.6.25
2025-11-28 00:37:07.378474 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2025-11-28 00:37:07.378481 | controller |  runc:
2025-11-28 00:37:07.378486 | controller |   Version:          1.1.10
2025-11-28 00:37:07.378492 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2025-11-28 00:37:07.378505 | controller |  docker-init:
2025-11-28 00:37:07.378512 | controller |   Version:          0.19.0
2025-11-28 00:37:07.378518 | controller |   GitCommit:        de40ad0
2025-11-28 00:37:07.571618 | controller | ok: Runtime: 0:00:00.054893
2025-11-28 00:37:07.586368 | 
2025-11-28 00:37:07.586527 | TASK [ensure-docker : Dump installed docker details]
2025-11-28 00:37:07.672988 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version:           24.0.7\n API version:       1.43\n Go version:        go1.20.10\n Git commit:        afdd53b\n Built:             Thu Oct 26 09:09:39 2023\n OS/Arch:           linux/amd64\n Context:           default\n\nServer: Docker Engine - Community\n Engine:\n  Version:          24.0.7\n  API version:      1.43 (minimum version 1.12)\n  Go version:       go1.20.10\n  Git commit:       311b9ff\n  Built:            Thu Oct 26 09:08:11 2023\n  OS/Arch:          linux/amd64\n  Experimental:     false\n containerd:\n  Version:          1.6.25\n  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n  Version:          1.1.10\n  GitCommit:        v1.1.10-0-g18a0cb0\n docker-init:\n  Version:          0.19.0\n  GitCommit:        de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2025-11-28 00:37:07.324444', 'end': '2025-11-28 00:37:07.379337', 'delta': '0:00:00.054893', 'msg': '', 'zuul_log_id': 'fa163efc-24cc-872c-3ee5-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version:           24.0.7', ' API version:       1.43', ' Go version:        go1.20.10', ' Git commit:        afdd53b', ' Built:             Thu Oct 26 09:09:39 2023', ' OS/Arch:           linux/amd64', ' Context:           default', '', 'Server: Docker Engine - Community', ' Engine:', '  Version:          24.0.7', '  API version:      1.43 (minimum version 1.12)', '  Go version:       go1.20.10', '  Git commit:       311b9ff', '  Built:            Thu Oct 26 09:08:11 2023', '  OS/Arch:          linux/amd64', '  Experimental:     false', ' containerd:', '  Version:          1.6.25', '  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', '  Version:          1.1.10', '  GitCommit:        v1.1.10-0-g18a0cb0', ' docker-init:', '  Version:          0.19.0', '  GitCommit:        de40ad0'], 'stderr_lines': [], 'failed': False}
2025-11-28 00:37:07.681636 | 
2025-11-28 00:37:07.681754 | TASK [Use buildset registry]
2025-11-28 00:37:07.710330 | controller | skipping: Conditional result was False
2025-11-28 00:37:07.727145 | 
2025-11-28 00:37:07.727249 | TASK [Ensure python3.8 is present]
2025-11-28 00:37:07.744583 | controller | skipping: Conditional result was False
2025-11-28 00:37:07.755608 | 
2025-11-28 00:37:07.755763 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-11-28 00:37:07.779730 | controller | ok
2025-11-28 00:37:07.808952 | 
2025-11-28 00:37:07.809094 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-11-28 00:37:15.965784 | controller | ok: Nothing to do
2025-11-28 00:37:15.979032 | 
2025-11-28 00:37:15.979183 | TASK [our-ensure-python : Also install python3-devel]
2025-11-28 00:37:30.186389 | controller | changed
2025-11-28 00:37:30.212403 | 
2025-11-28 00:37:30.212532 | TASK [Run ensure-virtualenv role]
2025-11-28 00:37:30.235290 | controller | ok
2025-11-28 00:37:30.264090 | 
2025-11-28 00:37:30.264220 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-11-28 00:37:31.061643 | controller | /usr/bin/virtualenv
2025-11-28 00:37:31.625186 | controller | ok: Runtime: 0:00:00.007549
2025-11-28 00:37:31.638496 | 
2025-11-28 00:37:31.638654 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-11-28 00:37:31.674445 | controller | skipping: Conditional result was False
2025-11-28 00:37:31.674931 | controller | changed: All items complete
2025-11-28 00:37:31.674989 | 
2025-11-28 00:37:31.702777 | 
2025-11-28 00:37:31.702982 | TASK [Find the full path of the Python interpreter]
2025-11-28 00:37:32.583444 | controller | /usr/bin/python3.8
2025-11-28 00:37:33.074913 | controller | ok
2025-11-28 00:37:33.089260 | 
2025-11-28 00:37:33.089397 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-11-28 00:37:35.776634 | controller | created virtual environment CPython3.8.18.final.0-64 in 1224ms
2025-11-28 00:37:35.954929 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-11-28 00:37:35.955019 | controller |   seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv)
2025-11-28 00:37:35.955045 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-11-28 00:37:35.955067 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-11-28 00:37:36.476896 | controller | changed
2025-11-28 00:37:36.517596 | 
2025-11-28 00:37:36.517773 | TASK [Set selinux package]
2025-11-28 00:37:36.537673 | controller | ok
2025-11-28 00:37:36.542624 | 
2025-11-28 00:37:36.542690 | TASK [Set selinux package (Fedora)]
2025-11-28 00:37:36.571945 | controller | ok
2025-11-28 00:37:36.576870 | 
2025-11-28 00:37:36.576929 | TASK [Install selinux into virtualenv]
2025-11-28 00:37:43.121552 | controller | Collecting selinux-please-lie-to-me
2025-11-28 00:37:43.179707 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-11-28 00:37:43.738764 | controller | Collecting setuptools<50.0.0
2025-11-28 00:37:43.745217 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-11-28 00:37:43.793555 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 18.6 MB/s eta 0:00:00
2025-11-28 00:37:43.907717 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-11-28 00:37:43.907993 | controller |   Attempting uninstall: setuptools
2025-11-28 00:37:43.909121 | controller |     Found existing installation: setuptools 62.6.0
2025-11-28 00:37:44.022875 | controller |     Uninstalling setuptools-62.6.0:
2025-11-28 00:37:44.036726 | controller |       Successfully uninstalled setuptools-62.6.0
2025-11-28 00:37:44.670333 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-11-28 00:37:44.852361 | controller |
2025-11-28 00:37:45.180548 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-11-28 00:37:45.180613 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-11-28 00:37:45.450888 | controller | ok: Runtime: 0:00:07.445016
2025-11-28 00:37:45.464633 | 
2025-11-28 00:37:45.464824 | TASK [Install pytest-forked into virtualenv]
2025-11-28 00:37:47.249021 | controller | Collecting pytest-forked
2025-11-28 00:37:47.306309 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-11-28 00:37:47.495160 | controller | Collecting pytest>=3.10
2025-11-28 00:37:47.500631 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2025-11-28 00:37:47.543827 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 9.0 MB/s eta 0:00:00
2025-11-28 00:37:47.594617 | controller | Collecting py
2025-11-28 00:37:47.600166 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-11-28 00:37:47.612661 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 10.5 MB/s eta 0:00:00
2025-11-28 00:37:47.705811 | controller | Collecting tomli>=1
2025-11-28 00:37:47.711353 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2025-11-28 00:37:47.762869 | controller | Collecting exceptiongroup>=1.0.0rc8
2025-11-28 00:37:47.768419 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2025-11-28 00:37:47.837854 | controller | Collecting packaging
2025-11-28 00:37:47.843608 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-11-28 00:37:47.853591 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 9.4 MB/s eta 0:00:00
2025-11-28 00:37:47.882158 | controller | Collecting iniconfig
2025-11-28 00:37:47.887304 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-11-28 00:37:47.938028 | controller | Collecting pluggy<2,>=1.5
2025-11-28 00:37:47.943265 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-11-28 00:37:48.024197 | controller | Collecting typing-extensions>=4.6.0
2025-11-28 00:37:48.029971 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2025-11-28 00:37:48.039140 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 6.8 MB/s eta 0:00:00
2025-11-28 00:37:48.134845 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-11-28 00:37:48.956703 | 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-11-28 00:37:48.960114 | controller |
2025-11-28 00:37:49.264752 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-11-28 00:37:49.264824 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-11-28 00:37:49.845998 | controller | ok: Runtime: 0:00:02.717031
2025-11-28 00:37:49.851867 | 
2025-11-28 00:37:49.851931 | TASK [Update pip]
2025-11-28 00:37:52.710451 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2025-11-28 00:37:53.221900 | controller | Collecting pip
2025-11-28 00:37:53.345202 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2025-11-28 00:37:53.471774 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 15.7 MB/s eta 0:00:00
2025-11-28 00:37:53.724307 | controller | Installing collected packages: pip
2025-11-28 00:37:53.724557 | controller |   Attempting uninstall: pip
2025-11-28 00:37:53.725725 | controller |     Found existing installation: pip 22.2.2
2025-11-28 00:37:54.444860 | controller |     Uninstalling pip-22.2.2:
2025-11-28 00:37:54.516391 | controller |       Successfully uninstalled pip-22.2.2
2025-11-28 00:37:56.732903 | controller | Successfully installed pip-25.0.1
2025-11-28 00:37:57.213313 | controller | ok: Runtime: 0:00:05.959195
2025-11-28 00:37:57.223923 | 
2025-11-28 00:37:57.224035 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-11-28 00:37:58.545997 | controller | changed
2025-11-28 00:37:58.555631 | 
2025-11-28 00:37:58.555822 | TASK [Install ansible into virtualenv]
2025-11-28 00:38:00.388487 | controller | Processing ./src/github.com/ansible/ansible
2025-11-28 00:38:00.390346 | controller |   Preparing metadata (setup.py): started
2025-11-28 00:38:01.199047 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-11-28 00:38:01.248559 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2025-11-28 00:38:01.263036 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-11-28 00:38:01.345004 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2025-11-28 00:38:01.351478 | controller |   Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.1 kB)
2025-11-28 00:38:01.779611 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2025-11-28 00:38:01.785014 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-11-28 00:38:01.808639 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (25.0)
2025-11-28 00:38:02.028988 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2025-11-28 00:38:02.034463 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2025-11-28 00:38:02.043326 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.8/site-packages (from cryptography->ansible-base==2.10.17.post0) (4.13.2)
2025-11-28 00:38:02.149805 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2025-11-28 00:38:02.156374 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2025-11-28 00:38:02.191810 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2025-11-28 00:38:02.199967 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-11-28 00:38:02.215253 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2025-11-28 00:38:02.270316 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 91.8 MB/s eta 0:00:00
2025-11-28 00:38:02.275878 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-11-28 00:38:02.291232 | controller | Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (806 kB)
2025-11-28 00:38:02.312937 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 38.4 MB/s eta 0:00:00
2025-11-28 00:38:02.318793 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2025-11-28 00:38:02.335109 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2025-11-28 00:38:02.347925 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-11-28 00:38:02.378230 | controller | Building wheels for collected packages: ansible-base
2025-11-28 00:38:02.379315 | controller |   Building wheel for ansible-base (setup.py): started
2025-11-28 00:38:04.403231 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2025-11-28 00:38:04.410355 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=5e072edd13e86a66f9288d14bcfd97dbc78ac007ef6b372ad9f5ac1d23e99a5e
2025-11-28 00:38:04.410649 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-lq23sur_/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2025-11-28 00:38:04.424556 | controller | Successfully built ansible-base
2025-11-28 00:38:04.478362 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2025-11-28 00:38:07.297532 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-base-2.10.17.post0 cffi-1.17.1 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23
2025-11-28 00:38:07.941482 | controller | ok: Runtime: 0:00:07.938440
2025-11-28 00:38:07.955448 | 
2025-11-28 00:38:07.955587 | TASK [Setup download-artifact-fork role]
2025-11-28 00:38:07.986254 | controller | ok
2025-11-28 00:38:08.022012 | 
2025-11-28 00:38:08.022115 | TASK [download-artifact-fork : Parse inventory response]
2025-11-28 00:38:08.078802 | controller | ok
2025-11-28 00:38:08.091518 | 
2025-11-28 00:38:08.091651 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-11-28 00:38:08.128717 | controller | skipping: Conditional result was False
2025-11-28 00:38:08.143355 | 
2025-11-28 00:38:08.143489 | TASK [download-artifact-fork : Parse build response]
2025-11-28 00:38:08.180276 | controller | skipping: Conditional result was False
2025-11-28 00:38:08.193598 | 
2025-11-28 00:38:08.193767 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-11-28 00:38:09.018051 | controller | changed
2025-11-28 00:38:09.030474 | 
2025-11-28 00:38:09.030618 | LOOP [download-artifact-fork : Download archive by type]
2025-11-28 00:38:09.065287 | controller | skipping: Conditional result was False
2025-11-28 00:38:09.065593 | 
2025-11-28 00:38:10.934320 | controller | changed: OK (20197 bytes)
2025-11-28 00:38:10.962912 | 
2025-11-28 00:38:10.963124 | TASK [deploy-artifacts : define __collections]
2025-11-28 00:38:11.001921 | controller | ok
2025-11-28 00:38:11.015494 | 
2025-11-28 00:38:11.015704 | LOOP [deploy-artifacts : Create list of collections]
2025-11-28 00:38:11.053004 | controller | skipping: Conditional result was False
2025-11-28 00:38:11.123590 | controller | ok:
2025-11-28 00:38:11.123742 | controller | {
2025-11-28 00:38:11.123776 | controller |   "branch": "main",
2025-11-28 00:38:11.123802 | controller |   "job": "build-ansible-collection",
2025-11-28 00:38:11.123826 | controller |   "metadata": {
2025-11-28 00:38:11.123849 | controller |     "type": "ansible_collection",
2025-11-28 00:38:11.123872 | controller |     "version": "5.0.0"
2025-11-28 00:38:11.123895 | controller |   },
2025-11-28 00:38:11.123918 | controller |   "name": "ansible.network",
2025-11-28 00:38:11.123940 | controller |   "project": "ansible-collections/ansible.network",
2025-11-28 00:38:11.123962 | controller |   "url": "https://fffe8cccde5ccce4b0b1-e741c22f6c3afb4d17467236bf7c8cfd.ssl.cf5.rackcdn.com/ansible/7fba9e5b294640c392afbc3922d334cd/artifacts/ansible-network-5.0.0.tar.gz"
2025-11-28 00:38:11.123993 | controller | }
2025-11-28 00:38:11.145712 | 
2025-11-28 00:38:11.145922 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-11-28 00:38:12.515665 | controller | Starting galaxy collection install process
2025-11-28 00:38:12.516288 | controller | Process install dependency map
2025-11-28 00:38:27.786134 | controller | Starting collection install process
2025-11-28 00:38:27.787400 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2025-11-28 00:38:27.816594 | controller | ansible.network (5.0.0) was installed successfully
2025-11-28 00:38:27.816744 | controller | Installing 'ansible.netcommon:8.2.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2025-11-28 00:38:27.816976 | 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-4266nx4q99lz/tmpxo67j818
2025-11-28 00:38:29.389433 | controller | ansible.netcommon (8.2.0) was installed successfully
2025-11-28 00:38:29.389516 | controller | Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2025-11-28 00:38:29.389530 | 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-4266nx4q99lz/tmpxo67j818
2025-11-28 00:38:31.038733 | controller | ansible.utils (6.0.0) was installed successfully
2025-11-28 00:38:31.038880 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2025-11-28 00:38:31.039102 | 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-4266nx4q99lz/tmpxo67j818
2025-11-28 00:38:33.283540 | controller | arista.eos (12.0.0) was installed successfully
2025-11-28 00:38:33.283680 | controller | Installing 'cisco.ios:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2025-11-28 00:38:33.283697 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4266nx4q99lz/tmpxo67j818
2025-11-28 00:38:35.736904 | controller | cisco.ios (11.1.1) was installed successfully
2025-11-28 00:38:35.737096 | controller | Installing 'cisco.iosxr:12.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2025-11-28 00:38:35.737360 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.1.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4266nx4q99lz/tmpxo67j818
2025-11-28 00:38:38.104323 | controller | cisco.iosxr (12.1.0) was installed successfully
2025-11-28 00:38:38.104551 | controller | Installing 'cisco.nxos:11.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2025-11-28 00:38:38.104574 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4266nx4q99lz/tmpxo67j818
2025-11-28 00:38:41.732035 | controller | cisco.nxos (11.1.0) was installed successfully
2025-11-28 00:38:41.732281 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2025-11-28 00:38:41.732318 | 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-4266nx4q99lz/tmpxo67j818
2025-11-28 00:38:43.860099 | controller | junipernetworks.junos (11.0.0) was installed successfully
2025-11-28 00:38:44.544582 | controller | ok: Runtime: 0:00:32.125147
2025-11-28 00:38:44.570834 | 
2025-11-28 00:38:44.570990 | TASK [podman may need iptables kernel modules]
2025-11-28 00:38:45.944029 | controller | ok: Runtime: 0:00:00.008673
2025-11-28 00:38:45.987470 | 
2025-11-28 00:38:45.987558 | PLAY RECAP
2025-11-28 00:38:45.987614 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2025-11-28 00:38:45.987640 | 
2025-11-28 00:38:46.106862 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-28 00:38:46.108951 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-11-28 00:38:46.764192 | 
2025-11-28 00:38:46.764316 | PLAY [controller]
2025-11-28 00:38:46.786074 | 
2025-11-28 00:38:46.786161 | TASK [Copy the galaxy.yml on the controller]
2025-11-28 00:38:50.619165 | controller | changed
2025-11-28 00:38:50.631125 | 
2025-11-28 00:38:50.631288 | TASK [Load information from galaxy.yml]
2025-11-28 00:38:50.669503 | controller | ok
2025-11-28 00:38:50.681995 | 
2025-11-28 00:38:50.682147 | LOOP [Enable FIPS mode]
2025-11-28 00:38:50.713802 | 
2025-11-28 00:38:50.714086 | LOOP [ansible-test : Include branch specific variables.]
2025-11-28 00:38:50.766720 | controller | ok: "/var/lib/zuul/builds/3ca1508cb9674091914af88c470dce29/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2025-11-28 00:38:50.766992 | 
2025-11-28 00:38:50.787326 | 
2025-11-28 00:38:50.787529 | TASK [ansible-test : Setup --skip-tags for test_options]
2025-11-28 00:38:50.835254 | controller | ok
2025-11-28 00:38:50.846803 | 
2025-11-28 00:38:50.846953 | TASK [ansible-test : Enable --inventory for network-integration]
2025-11-28 00:38:50.883562 | controller | skipping: Conditional result was False
2025-11-28 00:38:50.918010 | 
2025-11-28 00:38:50.918211 | TASK [ansible-test : Enable --retry-on-error]
2025-11-28 00:38:50.947450 | controller | skipping: Conditional result was False
2025-11-28 00:38:50.963085 | 
2025-11-28 00:38:50.963307 | TASK [ansible-test : Enable --continue-on-error]
2025-11-28 00:38:51.012485 | controller | ok
2025-11-28 00:38:51.024331 | 
2025-11-28 00:38:51.024465 | TASK [ansible-test : Enable --requirements]
2025-11-28 00:38:51.051559 | controller | skipping: Conditional result was False
2025-11-28 00:38:51.064484 | 
2025-11-28 00:38:51.064621 | TASK [ansible-test : Adjust options for unit tests]
2025-11-28 00:38:51.091758 | controller | skipping: Conditional result was False
2025-11-28 00:38:51.104799 | 
2025-11-28 00:38:51.104934 | TASK [ansible-test : Install requirements with unit tests]
2025-11-28 00:38:51.180102 | controller | ok
2025-11-28 00:38:51.188134 | 
2025-11-28 00:38:51.188227 | TASK [ansible-test : Set --skip-test]
2025-11-28 00:38:51.225586 | controller | skipping: Conditional result was False
2025-11-28 00:38:51.240278 | 
2025-11-28 00:38:51.240481 | TASK [ansible-test : Reduce verbosity]
2025-11-28 00:38:51.275079 | controller | ok
2025-11-28 00:38:51.283475 | 
2025-11-28 00:38:51.283632 | TASK [ansible-test : Setup --python option]
2025-11-28 00:38:51.299088 | controller | skipping: Conditional result was False
2025-11-28 00:38:51.309154 | 
2025-11-28 00:38:51.309314 | TASK [ansible-test : Setup --docker option]
2025-11-28 00:38:51.343755 | controller | ok
2025-11-28 00:38:51.356038 | 
2025-11-28 00:38:51.356211 | TASK [ansible-test : check if MANIFEST.json exists]
2025-11-28 00:38:52.515583 | controller | ok
2025-11-28 00:38:52.527922 | 
2025-11-28 00:38:52.528056 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2025-11-28 00:38:52.555502 | controller | skipping: Conditional result was False
2025-11-28 00:38:52.568991 | 
2025-11-28 00:38:52.569135 | TASK [ansible-test : Load information from MANIFEST.json]
2025-11-28 00:38:52.596503 | controller | skipping: Conditional result was False
2025-11-28 00:38:52.611022 | 
2025-11-28 00:38:52.611174 | TASK [ansible-test : Setup location of project for integration tests]
2025-11-28 00:38:52.638445 | controller | skipping: Conditional result was False
2025-11-28 00:38:52.651432 | 
2025-11-28 00:38:52.651568 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2025-11-28 00:38:54.023564 | controller | ok
2025-11-28 00:38:54.035522 | 
2025-11-28 00:38:54.035656 | TASK [ansible-test : Load information from galaxy.yml]
2025-11-28 00:38:54.086297 | controller | ok
2025-11-28 00:38:54.102407 | 
2025-11-28 00:38:54.102581 | TASK [ansible-test : Setup location of project for integration tests]
2025-11-28 00:38:54.139623 | controller | ok
2025-11-28 00:38:54.147248 | 
2025-11-28 00:38:54.147338 | TASK [ansible-test : Setup minimum test requirements]
2025-11-28 00:38:54.180050 | controller | ok
2025-11-28 00:38:54.187415 | 
2025-11-28 00:38:54.187500 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2025-11-28 00:38:55.082786 | controller | ok
2025-11-28 00:38:55.089181 | 
2025-11-28 00:38:55.089241 | TASK [ansible-test : Install python requirements]
2025-11-28 00:38:56.439864 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-11-28 00:38:56.459117 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2025-11-28 00:38:56.493587 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-11-28 00:38:56.498784 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2025-11-28 00:38:56.509147 | controller | Requirement already satisfied: pyyaml in ./venv/lib64/python3.8/site-packages (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1)) (6.0.3)
2025-11-28 00:38:56.517323 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2025-11-28 00:38:56.532109 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2025-11-28 00:38:56.582644 | controller | Installing collected packages: pathspec, yamllint
2025-11-28 00:38:56.659043 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2025-11-28 00:38:57.443619 | controller | ok: Runtime: 0:00:00.982372
2025-11-28 00:38:57.449868 | 
2025-11-28 00:38:57.449936 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2025-11-28 00:38:58.225838 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2025-11-28 00:38:58.805130 | controller | ERROR
2025-11-28 00:38:58.805293 | controller | {
2025-11-28 00:38:58.805322 | controller |   "delta": "0:00:00.007740",
2025-11-28 00:38:58.805342 | controller |   "end": "2025-11-28 00:38:58.226105",
2025-11-28 00:38:58.805358 | controller |   "msg": "non-zero return code",
2025-11-28 00:38:58.805374 | controller |   "rc": 1,
2025-11-28 00:38:58.805390 | controller |   "start": "2025-11-28 00:38:58.218365"
2025-11-28 00:38:58.805405 | controller | }
2025-11-28 00:38:58.805428 | controller | ERROR: Ignoring Errors
2025-11-28 00:38:58.811644 | 
2025-11-28 00:38:58.811740 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2025-11-28 00:38:59.192551 | controller | skipping: Conditional result was False
2025-11-28 00:38:59.208186 | 
2025-11-28 00:38:59.208369 | TASK [ansible-test : Enable persistent connection logging]
2025-11-28 00:39:00.806351 | controller | changed: section and option added
2025-11-28 00:39:00.813826 | 
2025-11-28 00:39:00.813991 | TASK [ansible-test : Set persistent ssh_type value]
2025-11-28 00:39:02.109246 | controller | changed: option added
2025-11-28 00:39:02.116991 | 
2025-11-28 00:39:02.117080 | TASK [ansible-test : Set ansible log path]
2025-11-28 00:39:03.332986 | controller | changed: section and option added
2025-11-28 00:39:03.342854 | 
2025-11-28 00:39:03.342967 | TASK [ansible-test : Identify the changed targets]
2025-11-28 00:39:03.703554 | controller | skipping: Conditional result was False
2025-11-28 00:39:03.723146 | 
2025-11-28 00:39:03.723358 | TASK [ansible-test : set_fact]
2025-11-28 00:39:03.794370 | controller | skipping: Conditional result was False
2025-11-28 00:39:03.809130 | 
2025-11-28 00:39:03.809284 | TASK [ansible-test : Set the targets]
2025-11-28 00:39:03.895082 | controller | ok
2025-11-28 00:39:03.903068 | 
2025-11-28 00:39:03.903158 | TASK [ansible-test : set_fact]
2025-11-28 00:39:03.928925 | controller | skipping: Conditional result was False
2025-11-28 00:39:03.943715 | 
2025-11-28 00:39:03.943890 | TASK [ansible-test : List the targets]
2025-11-28 00:39:04.312797 | controller | skipping: Conditional result was False
2025-11-28 00:39:04.328211 | 
2025-11-28 00:39:04.328389 | TASK [ansible-test : set_fact]
2025-11-28 00:39:04.370416 | controller | skipping: Conditional result was False
2025-11-28 00:39:04.386292 | 
2025-11-28 00:39:04.386437 | TASK [ansible-test : set_fact]
2025-11-28 00:39:04.413428 | controller | skipping: Conditional result was False
2025-11-28 00:39:04.426953 | 
2025-11-28 00:39:04.427102 | LOOP [ansible-test : set_fact]
2025-11-28 00:39:04.512126 | 
2025-11-28 00:39:04.512300 | TASK [ansible-test : set_fact]
2025-11-28 00:39:04.525908 | controller | skipping: Conditional result was False
2025-11-28 00:39:04.533526 | 
2025-11-28 00:39:04.533603 | TASK [ansible-test : debug]
2025-11-28 00:39:04.549193 | controller | skipping: Conditional result was False
2025-11-28 00:39:04.557515 | 
2025-11-28 00:39:04.557590 | TASK [ansible-test : debug]
2025-11-28 00:39:04.587715 | About to run: ansible-test sanity --requirements --docker -v
2025-11-28 00:39:04.593136 | 
2025-11-28 00:39:04.593202 | TASK [ansible-test : Run the test suite]
2025-11-28 00:39:06.226579 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2025-11-28 00:39:06.335869 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2025-11-28 00:39:07.937445 | controller | 2.9.0: Pulling from ansible/default-test-container
2025-11-28 00:39:07.937508 | controller | 171857c49d0f: Pulling fs layer
2025-11-28 00:39:07.937554 | controller | 419640447d26: Pulling fs layer
2025-11-28 00:39:07.937563 | controller | 61e52f862619: Pulling fs layer
2025-11-28 00:39:07.937570 | controller | 851de0426e26: Pulling fs layer
2025-11-28 00:39:07.937581 | controller | 37425716ea5b: Pulling fs layer
2025-11-28 00:39:07.937590 | controller | cd9b0c439616: Pulling fs layer
2025-11-28 00:39:07.937596 | controller | d4612dbd8855: Pulling fs layer
2025-11-28 00:39:07.937602 | controller | 554699364730: Pulling fs layer
2025-11-28 00:39:07.937612 | controller | 1ff15d4bdb3e: Pulling fs layer
2025-11-28 00:39:07.939031 | controller | 5f2e4724adea: Pulling fs layer
2025-11-28 00:39:07.939045 | controller | a02d9a95ccb8: Pulling fs layer
2025-11-28 00:39:07.939051 | controller | 989f4ce370cb: Pulling fs layer
2025-11-28 00:39:07.939058 | controller | 0d7f52cdf9df: Pulling fs layer
2025-11-28 00:39:07.939064 | controller | c423742351d5: Pulling fs layer
2025-11-28 00:39:07.939082 | controller | 109236341d21: Pulling fs layer
2025-11-28 00:39:07.939092 | controller | 07a2f61c96b8: Pulling fs layer
2025-11-28 00:39:07.939098 | controller | 041f122f27ae: Pulling fs layer
2025-11-28 00:39:07.939106 | controller | ca6f5098b12e: Pulling fs layer
2025-11-28 00:39:07.939112 | controller | 1eb3e7b2afd9: Pulling fs layer
2025-11-28 00:39:07.939118 | controller | 18a0b750727a: Pulling fs layer
2025-11-28 00:39:07.939124 | controller | 17f2f596716c: Pulling fs layer
2025-11-28 00:39:07.939132 | controller | f35a9998a9bd: Pulling fs layer
2025-11-28 00:39:07.939138 | controller | fdfc10566405: Pulling fs layer
2025-11-28 00:39:07.939144 | controller | 1ff1c442c5fe: Pulling fs layer
2025-11-28 00:39:07.939151 | controller | a3443c4a17ed: Pulling fs layer
2025-11-28 00:39:07.939157 | controller | 0edcf9fb2b15: Pulling fs layer
2025-11-28 00:39:07.939163 | controller | 5af43b69e845: Pulling fs layer
2025-11-28 00:39:07.939169 | controller | 1651773effb2: Pulling fs layer
2025-11-28 00:39:07.939175 | controller | 79e23c5c0fe4: Pulling fs layer
2025-11-28 00:39:07.939188 | controller | e5058b1faaa6: Pulling fs layer
2025-11-28 00:39:07.939194 | controller | 846775490569: Pulling fs layer
2025-11-28 00:39:07.939206 | controller | 851de0426e26: Waiting
2025-11-28 00:39:07.939773 | controller | 37425716ea5b: Waiting
2025-11-28 00:39:07.939791 | controller | cd9b0c439616: Waiting
2025-11-28 00:39:07.939798 | controller | d4612dbd8855: Waiting
2025-11-28 00:39:07.939805 | controller | 554699364730: Waiting
2025-11-28 00:39:07.939824 | controller | 1ff15d4bdb3e: Waiting
2025-11-28 00:39:07.939839 | controller | 5f2e4724adea: Waiting
2025-11-28 00:39:07.939849 | controller | a02d9a95ccb8: Waiting
2025-11-28 00:39:07.941608 | controller | 989f4ce370cb: Waiting
2025-11-28 00:39:07.941641 | controller | 0d7f52cdf9df: Waiting
2025-11-28 00:39:07.941648 | controller | c423742351d5: Waiting
2025-11-28 00:39:07.941654 | controller | 109236341d21: Waiting
2025-11-28 00:39:07.941660 | controller | 07a2f61c96b8: Waiting
2025-11-28 00:39:07.941666 | controller | 041f122f27ae: Waiting
2025-11-28 00:39:07.941672 | controller | ca6f5098b12e: Waiting
2025-11-28 00:39:07.941684 | controller | 1eb3e7b2afd9: Waiting
2025-11-28 00:39:08.351198 | controller | 18a0b750727a: Waiting
2025-11-28 00:39:08.351281 | controller | 17f2f596716c: Waiting
2025-11-28 00:39:08.351296 | controller | f35a9998a9bd: Waiting
2025-11-28 00:39:08.351302 | controller | fdfc10566405: Waiting
2025-11-28 00:39:08.351308 | controller | 1ff1c442c5fe: Waiting
2025-11-28 00:39:08.351314 | controller | a3443c4a17ed: Waiting
2025-11-28 00:39:08.351333 | controller | 0edcf9fb2b15: Waiting
2025-11-28 00:39:08.351340 | controller | 5af43b69e845: Waiting
2025-11-28 00:39:08.351346 | controller | 1651773effb2: Waiting
2025-11-28 00:39:08.351352 | controller | 79e23c5c0fe4: Waiting
2025-11-28 00:39:08.351358 | controller | e5058b1faaa6: Waiting
2025-11-28 00:39:08.351366 | controller | 846775490569: Waiting
2025-11-28 00:39:08.351384 | controller | 61e52f862619: Verifying Checksum
2025-11-28 00:39:08.399401 | controller | 61e52f862619: Download complete
2025-11-28 00:39:08.399487 | controller | 419640447d26: Verifying Checksum
2025-11-28 00:39:08.668726 | controller | 419640447d26: Download complete
2025-11-28 00:39:08.668822 | controller | 171857c49d0f: Verifying Checksum
2025-11-28 00:39:08.669666 | controller | 171857c49d0f: Download complete
2025-11-28 00:39:08.792177 | controller | 851de0426e26: Verifying Checksum
2025-11-28 00:39:09.075556 | controller | 851de0426e26: Download complete
2025-11-28 00:39:09.075644 | controller | cd9b0c439616: Verifying Checksum
2025-11-28 00:39:09.255259 | controller | cd9b0c439616: Download complete
2025-11-28 00:39:09.255354 | controller | d4612dbd8855: Verifying Checksum
2025-11-28 00:39:09.705224 | controller | d4612dbd8855: Download complete
2025-11-28 00:39:09.705314 | controller | 1ff15d4bdb3e: Verifying Checksum
2025-11-28 00:39:10.285231 | controller | 1ff15d4bdb3e: Download complete
2025-11-28 00:39:10.285331 | controller | 5f2e4724adea: Verifying Checksum
2025-11-28 00:39:10.285419 | controller | 5f2e4724adea: Download complete
2025-11-28 00:39:10.839305 | controller | a02d9a95ccb8: Verifying Checksum
2025-11-28 00:39:11.481165 | controller | a02d9a95ccb8: Download complete
2025-11-28 00:39:11.481238 | controller | 989f4ce370cb: Verifying Checksum
2025-11-28 00:39:11.481373 | controller | 989f4ce370cb: Download complete
2025-11-28 00:39:11.952859 | controller | 0d7f52cdf9df: Verifying Checksum
2025-11-28 00:39:12.456340 | controller | 0d7f52cdf9df: Download complete
2025-11-28 00:39:12.456429 | controller | c423742351d5: Verifying Checksum
2025-11-28 00:39:13.050287 | controller | c423742351d5: Download complete
2025-11-28 00:39:13.050361 | controller | 554699364730: Verifying Checksum
2025-11-28 00:39:13.053420 | controller | 554699364730: Download complete
2025-11-28 00:39:13.792195 | controller | 07a2f61c96b8: Verifying Checksum
2025-11-28 00:39:14.349693 | controller | 07a2f61c96b8: Download complete
2025-11-28 00:39:14.349776 | controller | 041f122f27ae: Verifying Checksum
2025-11-28 00:39:14.756275 | controller | 041f122f27ae: Download complete
2025-11-28 00:39:14.756359 | controller | 109236341d21: Verifying Checksum
2025-11-28 00:39:14.950520 | controller | 109236341d21: Download complete
2025-11-28 00:39:14.950600 | controller | ca6f5098b12e: Verifying Checksum
2025-11-28 00:39:15.357210 | controller | ca6f5098b12e: Download complete
2025-11-28 00:39:15.357307 | controller | 1eb3e7b2afd9: Verifying Checksum
2025-11-28 00:39:15.487093 | controller | 1eb3e7b2afd9: Download complete
2025-11-28 00:39:15.487185 | controller | 18a0b750727a: Verifying Checksum
2025-11-28 00:39:15.498261 | controller | 18a0b750727a: Download complete
2025-11-28 00:39:15.498346 | controller | 171857c49d0f: Pull complete
2025-11-28 00:39:15.525325 | controller | 419640447d26: Pull complete
2025-11-28 00:39:15.550835 | controller | 61e52f862619: Pull complete
2025-11-28 00:39:15.599533 | controller | 851de0426e26: Pull complete
2025-11-28 00:39:15.781296 | controller | 17f2f596716c: Verifying Checksum
2025-11-28 00:39:15.781512 | controller | 17f2f596716c: Download complete
2025-11-28 00:39:15.901978 | controller | f35a9998a9bd: Verifying Checksum
2025-11-28 00:39:15.902262 | controller | f35a9998a9bd: Download complete
2025-11-28 00:39:16.183344 | controller | fdfc10566405: Verifying Checksum
2025-11-28 00:39:16.183537 | controller | fdfc10566405: Download complete
2025-11-28 00:39:16.266532 | controller | 1ff1c442c5fe: Verifying Checksum
2025-11-28 00:39:16.266718 | controller | 1ff1c442c5fe: Download complete
2025-11-28 00:39:16.751461 | controller | a3443c4a17ed: Verifying Checksum
2025-11-28 00:39:16.751588 | controller | a3443c4a17ed: Download complete
2025-11-28 00:39:16.807220 | controller | 37425716ea5b: Verifying Checksum
2025-11-28 00:39:16.807377 | controller | 37425716ea5b: Download complete
2025-11-28 00:39:17.198858 | controller | 0edcf9fb2b15: Verifying Checksum
2025-11-28 00:39:17.201261 | controller | 0edcf9fb2b15: Download complete
2025-11-28 00:39:17.464234 | controller | 5af43b69e845: Verifying Checksum
2025-11-28 00:39:17.464611 | controller | 5af43b69e845: Download complete
2025-11-28 00:39:17.523285 | controller | 1651773effb2: Verifying Checksum
2025-11-28 00:39:17.523471 | controller | 1651773effb2: Download complete
2025-11-28 00:39:17.807483 | controller | 79e23c5c0fe4: Verifying Checksum
2025-11-28 00:39:17.807982 | controller | 79e23c5c0fe4: Download complete
2025-11-28 00:39:18.147516 | controller | 846775490569: Verifying Checksum
2025-11-28 00:39:18.147719 | controller | 846775490569: Download complete
2025-11-28 00:39:18.253353 | controller | e5058b1faaa6: Verifying Checksum
2025-11-28 00:39:18.253681 | controller | e5058b1faaa6: Download complete
2025-11-28 00:39:34.566532 | controller | 37425716ea5b: Pull complete
2025-11-28 00:39:34.590275 | controller | cd9b0c439616: Pull complete
2025-11-28 00:39:34.613323 | controller | d4612dbd8855: Pull complete
2025-11-28 00:39:39.391072 | controller | 554699364730: Pull complete
2025-11-28 00:39:39.429028 | controller | 1ff15d4bdb3e: Pull complete
2025-11-28 00:39:39.455766 | controller | 5f2e4724adea: Pull complete
2025-11-28 00:39:39.500477 | controller | a02d9a95ccb8: Pull complete
2025-11-28 00:39:39.523524 | controller | 989f4ce370cb: Pull complete
2025-11-28 00:39:39.545742 | controller | 0d7f52cdf9df: Pull complete
2025-11-28 00:39:39.574113 | controller | c423742351d5: Pull complete
2025-11-28 00:39:41.709861 | controller | 109236341d21: Pull complete
2025-11-28 00:39:41.732770 | controller | 07a2f61c96b8: Pull complete
2025-11-28 00:39:41.756018 | controller | 041f122f27ae: Pull complete
2025-11-28 00:39:41.923086 | controller | ca6f5098b12e: Pull complete
2025-11-28 00:39:41.980698 | controller | 1eb3e7b2afd9: Pull complete
2025-11-28 00:39:42.030867 | controller | 18a0b750727a: Pull complete
2025-11-28 00:39:42.051376 | controller | 17f2f596716c: Pull complete
2025-11-28 00:39:42.071521 | controller | f35a9998a9bd: Pull complete
2025-11-28 00:39:42.097306 | controller | fdfc10566405: Pull complete
2025-11-28 00:39:42.121596 | controller | 1ff1c442c5fe: Pull complete
2025-11-28 00:39:43.763195 | controller | a3443c4a17ed: Pull complete
2025-11-28 00:39:45.819762 | controller | 0edcf9fb2b15: Pull complete
2025-11-28 00:39:48.809169 | controller | 5af43b69e845: Pull complete
2025-11-28 00:39:53.550356 | controller | 1651773effb2: Pull complete
2025-11-28 00:39:56.332326 | controller | 79e23c5c0fe4: Pull complete
2025-11-28 00:39:58.140894 | controller | e5058b1faaa6: Pull complete
2025-11-28 00:40:00.701412 | controller | 846775490569: Pull complete
2025-11-28 00:40:00.720874 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2025-11-28 00:40:00.723932 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2025-11-28 00:40:00.730309 | controller | quay.io/ansible/default-test-container:2.9.0
2025-11-28 00:40:00.735200 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2025-11-28 00:40:00.765979 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2025-11-28 00:40:02.244071 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2025-11-28 00:40:02.245415 | controller | 04a5f4cda3ee: Pulling fs layer
2025-11-28 00:40:02.245569 | controller | ff496a88c8ed: Pulling fs layer
2025-11-28 00:40:02.245664 | controller | 0ce83f459fe7: Pulling fs layer
2025-11-28 00:40:02.245759 | controller | 2e5170e1f099: Pulling fs layer
2025-11-28 00:40:02.245860 | controller | 7641eb41b08c: Pulling fs layer
2025-11-28 00:40:02.246025 | controller | ad15fa9da398: Pulling fs layer
2025-11-28 00:40:02.246129 | controller | 087d91352424: Pulling fs layer
2025-11-28 00:40:02.246253 | controller | 8b92efd6a100: Pulling fs layer
2025-11-28 00:40:02.246350 | controller | 2e5170e1f099: Waiting
2025-11-28 00:40:02.246442 | controller | 7641eb41b08c: Waiting
2025-11-28 00:40:02.246533 | controller | ad15fa9da398: Waiting
2025-11-28 00:40:02.246631 | controller | 087d91352424: Waiting
2025-11-28 00:40:02.247242 | controller | 8b92efd6a100: Waiting
2025-11-28 00:40:02.635484 | controller | ff496a88c8ed: Verifying Checksum
2025-11-28 00:40:02.635670 | controller | ff496a88c8ed: Download complete
2025-11-28 00:40:02.803921 | controller | 0ce83f459fe7: Verifying Checksum
2025-11-28 00:40:02.804139 | controller | 0ce83f459fe7: Download complete
2025-11-28 00:40:02.897686 | controller | 04a5f4cda3ee: Verifying Checksum
2025-11-28 00:40:02.900160 | controller | 04a5f4cda3ee: Download complete
2025-11-28 00:40:03.235531 | controller | 2e5170e1f099: Verifying Checksum
2025-11-28 00:40:03.235664 | controller | 2e5170e1f099: Download complete
2025-11-28 00:40:03.247498 | controller | 7641eb41b08c: Verifying Checksum
2025-11-28 00:40:03.247831 | controller | 7641eb41b08c: Download complete
2025-11-28 00:40:03.270986 | controller | ad15fa9da398: Verifying Checksum
2025-11-28 00:40:03.271586 | controller | ad15fa9da398: Download complete
2025-11-28 00:40:03.620048 | controller | 8b92efd6a100: Verifying Checksum
2025-11-28 00:40:03.620256 | controller | 8b92efd6a100: Download complete
2025-11-28 00:40:03.716679 | controller | 087d91352424: Verifying Checksum
2025-11-28 00:40:03.717050 | controller | 087d91352424: Download complete
2025-11-28 00:40:04.891546 | controller | 04a5f4cda3ee: Pull complete
2025-11-28 00:40:04.948509 | controller | ff496a88c8ed: Pull complete
2025-11-28 00:40:04.970127 | controller | 0ce83f459fe7: Pull complete
2025-11-28 00:40:06.671200 | controller | 2e5170e1f099: Pull complete
2025-11-28 00:40:06.708111 | controller | 7641eb41b08c: Pull complete
2025-11-28 00:40:06.744761 | controller | ad15fa9da398: Pull complete
2025-11-28 00:40:07.726565 | controller | 087d91352424: Pull complete
2025-11-28 00:40:07.766454 | controller | 8b92efd6a100: Pull complete
2025-11-28 00:40:07.792502 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2025-11-28 00:40:07.799559 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2025-11-28 00:40:07.808741 | controller | quay.io/ansible/pypi-test-container:1.0.0
2025-11-28 00:40:07.813002 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2025-11-28 00:40:14.567167 | controller | Run command: docker inspect aa4ebb278dc15e1c4b16ed2244b64e98a47801fbb8d9d0ea2931dee72ae340c3
2025-11-28 00:40:14.624640 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2025-11-28 00:40:14.638853 | controller | Including collection: ansible.netcommon (292 files)
2025-11-28 00:40:14.638939 | controller | Including collection: ansible.network (23 files)
2025-11-28 00:40:14.650131 | controller | Including collection: ansible.utils (442 files)
2025-11-28 00:40:14.689184 | controller | Including collection: arista.eos (1022 files)
2025-11-28 00:40:14.732353 | controller | Including collection: cisco.ios (1172 files)
2025-11-28 00:40:14.777589 | controller | Including collection: cisco.iosxr (1043 files)
2025-11-28 00:40:14.848191 | controller | Including collection: cisco.nxos (1684 files)
2025-11-28 00:40:14.887597 | controller | Including collection: junipernetworks.junos (963 files)
2025-11-28 00:40:14.927581 | controller | Creating a payload archive containing 7367 files...
2025-11-28 00:40:16.937081 | controller | Created a 5982169 byte payload archive containing 7367 files in 2 seconds.
2025-11-28 00:40:16.943510 | controller | Assuming Docker is available on localhost.
2025-11-28 00:40:16.944100 | controller | Run command: docker run --detach --volume /sys/fs/cgroup:/sys/fs/cgroup:ro --privileged=false --security-opt seccomp=unconfined --volume /var/run/docker.sock:/var/run/docker.sock quay.io/ansible/default-test-container:2.9.0
2025-11-28 00:40:17.410047 | controller | Run command: docker exec -i 2bfffd75e852b1bb42f2a3667a238170bb2da39f1aa481131d87fc64b982f6fd dd of=/root/docker.sh bs=65536
2025-11-28 00:40:17.500403 | controller | Run command: docker exec 2bfffd75e852b1bb42f2a3667a238170bb2da39f1aa481131d87fc64b982f6fd /bin/bash /root/docker.sh
2025-11-28 00:40:17.578051 | controller | Run command: docker exec -i 2bfffd75e852b1bb42f2a3667a238170bb2da39f1aa481131d87fc64b982f6fd dd of=/root/test.tgz bs=65536
2025-11-28 00:40:17.673099 | controller | Run command: docker exec 2bfffd75e852b1bb42f2a3667a238170bb2da39f1aa481131d87fc64b982f6fd tar oxzf /root/test.tgz -C /root
2025-11-28 00:40:18.580613 | controller | Run command: docker exec 2bfffd75e852b1bb42f2a3667a238170bb2da39f1aa481131d87fc64b982f6fd /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-60klbw4b.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2025-11-28 00:40:18.970916 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2025-11-28 00:40:18.971299 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2025-11-28 00:40:18.971608 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2025-11-28 00:40:18.973773 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2025-11-28 00:40:18.974610 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2025-11-28 00:40:18.981287 | controller | No tests applicable.
2025-11-28 00:40:18.981577 | controller | Running sanity test 'ansible-doc' with Python 3.6
2025-11-28 00:40:18.981595 | controller | No tests applicable.
2025-11-28 00:40:18.982127 | controller | Running sanity test 'changelog' with Python 3.6
2025-11-28 00:40:18.982145 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/ansible-test.txt
2025-11-28 00:40:19.868418 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt
2025-11-28 00:40:20.448113 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2025-11-28 00:40:35.474222 | controller |   WARNING: Retrying (Retry(total=4, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-11-28 00:40:50.994549 | controller |   WARNING: Retrying (Retry(total=3, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-11-28 00:41:07.002595 | controller |   WARNING: Retrying (Retry(total=2, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-11-28 00:41:24.022572 | controller |   WARNING: Retrying (Retry(total=1, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-11-28 00:41:43.043854 | controller |   WARNING: Retrying (Retry(total=0, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-11-28 00:41:58.056032 | controller |   ERROR: Could not find a version that satisfies the requirement antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2)) (from versions: none)
2025-11-28 00:41:58.056119 | controller | ERROR: No matching distribution found for antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2025-11-28 00:41:58.234625 | controller | ERROR: Command "/usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt" returned exit status 1.
2025-11-28 00:41:58.236089 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2025-11-28 00:41:58.308108 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2025-11-28 00:41:58.308181 | controller | Removing custom PyPI hosts entries: /etc/hosts
2025-11-28 00:41:58.308204 | controller | Run command: docker exec 2bfffd75e852b1bb42f2a3667a238170bb2da39f1aa481131d87fc64b982f6fd tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2025-11-28 00:41:58.407886 | controller | Run command: docker exec -i 2bfffd75e852b1bb42f2a3667a238170bb2da39f1aa481131d87fc64b982f6fd dd if=/root/output.tgz bs=65536
2025-11-28 00:41:58.495083 | controller | Run command: tar oxzf /tmp/ansible-result-13pxss2a.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2025-11-28 00:41:58.517089 | controller | Run command: docker rm -f aa4ebb278dc15e1c4b16ed2244b64e98a47801fbb8d9d0ea2931dee72ae340c3
2025-11-28 00:41:58.818347 | controller | Run command: docker rm -f 2bfffd75e852b1bb42f2a3667a238170bb2da39f1aa481131d87fc64b982f6fd
2025-11-28 00:41:59.477075 | controller | ERROR: Command "docker exec 2bfffd75e852b1bb42f2a3667a238170bb2da39f1aa481131d87fc64b982f6fd /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-60klbw4b.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2025-11-28 00:42:00.094548 | controller | ERROR
2025-11-28 00:42:00.094845 | controller | {
2025-11-28 00:42:00.094907 | controller |   "delta": "0:02:53.858048",
2025-11-28 00:42:00.094951 | controller |   "end": "2025-11-28 00:41:59.550383",
2025-11-28 00:42:00.094989 | controller |   "msg": "non-zero return code",
2025-11-28 00:42:00.095040 | controller |   "rc": 1,
2025-11-28 00:42:00.095078 | controller |   "start": "2025-11-28 00:39:05.692335"
2025-11-28 00:42:00.095114 | controller | }
failure
2025-11-28 00:42:00.101482 | 
2025-11-28 00:42:00.101584 | PLAY RECAP
2025-11-28 00:42:00.101710 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2025-11-28 00:42:00.101765 | 
2025-11-28 00:42:00.223936 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-11-28 00:42:00.224871 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-28 00:42:00.859427 | 
2025-11-28 00:42:00.859562 | PLAY [all]
2025-11-28 00:42:00.883358 | 
2025-11-28 00:42:00.883527 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-11-28 00:42:03.896573 | controller | changed: non-zero return code
2025-11-28 00:42:03.909444 | 
2025-11-28 00:42:03.909609 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-11-28 00:42:03.935937 | controller | skipping: Conditional result was False
2025-11-28 00:42:03.944069 | 
2025-11-28 00:42:03.944166 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-11-28 00:42:03.978505 | 
2025-11-28 00:42:03.978730 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-11-28 00:42:04.013001 | 
2025-11-28 00:42:04.013219 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-11-28 00:42:04.038394 | controller | skipping: Conditional result was False
2025-11-28 00:42:04.046773 | 
2025-11-28 00:42:04.046867 | LOOP [fetch-subunit-output : Generate subunit file]
2025-11-28 00:42:04.080152 | 
2025-11-28 00:42:04.080338 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-11-28 00:42:04.105478 | controller | skipping: Conditional result was False
2025-11-28 00:42:04.115681 | 
2025-11-28 00:42:04.115797 | TASK [fetch-subunit-output : Remove the temporary file]
2025-11-28 00:42:04.141117 | controller | skipping: Conditional result was False
2025-11-28 00:42:04.150016 | 
2025-11-28 00:42:04.150123 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-11-28 00:42:04.175421 | controller | skipping: Conditional result was False
2025-11-28 00:42:04.212493 | 
2025-11-28 00:42:04.212630 | PLAY RECAP
2025-11-28 00:42:04.212712 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-28 00:42:04.212746 | 
2025-11-28 00:42:04.322242 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-28 00:42:04.323920 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-11-28 00:42:04.928568 | 
2025-11-28 00:42:04.928768 | PLAY [all:!appliance*]
2025-11-28 00:42:04.953531 | 
2025-11-28 00:42:04.953656 | TASK [unregister the node]
2025-11-28 00:42:05.308870 | controller | skipping: Conditional result was False
2025-11-28 00:42:05.322398 | 
2025-11-28 00:42:05.322561 | TASK [include_role : fetch-output]
2025-11-28 00:42:05.361115 | controller | ok
2025-11-28 00:42:05.399114 | 
2025-11-28 00:42:05.399206 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-28 00:42:05.444862 | controller | skipping: Conditional result was False
2025-11-28 00:42:05.458202 | 
2025-11-28 00:42:05.458366 | TASK [fetch-output : Set log path for single node]
2025-11-28 00:42:05.517707 | controller | ok
2025-11-28 00:42:05.529497 | 
2025-11-28 00:42:05.529630 | LOOP [fetch-output : Ensure local output dirs]
2025-11-28 00:42:05.989025 | controller -> localhost | ok: "/var/lib/zuul/builds/3ca1508cb9674091914af88c470dce29/work/logs"
2025-11-28 00:42:06.251112 | controller -> localhost | changed: "/var/lib/zuul/builds/3ca1508cb9674091914af88c470dce29/work/artifacts"
2025-11-28 00:42:06.483977 | controller -> localhost | changed: "/var/lib/zuul/builds/3ca1508cb9674091914af88c470dce29/work/docs"
2025-11-28 00:42:06.499805 | 
2025-11-28 00:42:06.499941 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-28 00:42:08.779506 | controller | changed:
2025-11-28 00:42:08.779877 | controller | .d..t...... ./
2025-11-28 00:42:08.779917 | controller | cd+++++++++ controller/
2025-11-28 00:42:08.779964 | controller | changed: All items complete
2025-11-28 00:42:08.779991 | 
2025-11-28 00:42:10.871480 | controller | changed: .d..t...... ./
2025-11-28 00:42:12.914628 | controller | changed: .d..t...... ./
2025-11-28 00:42:12.943835 | 
2025-11-28 00:42:12.944039 | TASK [include_role : fetch-output-openshift]
2025-11-28 00:42:12.963436 | controller | skipping: Conditional result was False
2025-11-28 00:42:12.974870 | 
2025-11-28 00:42:12.975024 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-28 00:42:13.014166 | controller | skipping: Conditional result was False
2025-11-28 00:42:13.033178 | controller | skipping: Conditional result was False
2025-11-28 00:42:13.078183 | 
2025-11-28 00:42:13.078327 | PLAY [localhost]
2025-11-28 00:42:13.096718 | 
2025-11-28 00:42:13.096832 | TASK [Run Zuul manifest role]
2025-11-28 00:42:13.118625 | localhost | ok
2025-11-28 00:42:13.137791 | 
2025-11-28 00:42:13.137918 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-28 00:42:13.486906 | localhost | changed
2025-11-28 00:42:13.505379 | 
2025-11-28 00:42:13.505521 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-28 00:42:13.540402 | localhost | ok
2025-11-28 00:42:13.557872 | 
2025-11-28 00:42:13.557990 | TASK [Set zuul-log-path fact]
2025-11-28 00:42:13.581811 | localhost | ok
2025-11-28 00:42:13.630977 | 
2025-11-28 00:42:13.631105 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-28 00:42:13.669230 | localhost | ok
2025-11-28 00:42:13.676919 | 
2025-11-28 00:42:13.676975 | LOOP [Run upload-logs-swift role]
2025-11-28 00:42:13.711883 | localhost | Output suppressed because no_log was given
2025-11-28 00:42:13.735384 | 
2025-11-28 00:42:13.735445 | TASK [Set zuul-log-path fact]
2025-11-28 00:42:13.769711 | localhost | skipping: Conditional result was False
2025-11-28 00:42:13.776884 | 
2025-11-28 00:42:13.776972 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-11-28 00:42:14.241470 | localhost -> localhost | ok: Runtime: 0:00:00.009232
2025-11-28 00:42:14.248816 | 
2025-11-28 00:42:14.248890 | TASK [upload-logs-swift : Upload logs to swift]