2026-01-22 00:29:45.300992 | Job console starting...
2026-01-22 00:29:45.310833 | Updating repositories
2026-01-22 00:29:45.499463 | Preparing job workspace
2026-01-22 00:29:49.583368 | Running Ansible setup...
2026-01-22 00:29:57.458138 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-22 00:29:58.091702 | 
2026-01-22 00:29:58.092042 | PLAY [localhost]
2026-01-22 00:29:58.102678 | 
2026-01-22 00:29:58.102842 | TASK [Gathering Facts]
2026-01-22 00:29:59.108540 | localhost | ok
2026-01-22 00:29:59.123278 | 
2026-01-22 00:29:59.123408 | TASK [Setup log path fact]
2026-01-22 00:29:59.142196 | localhost | ok
2026-01-22 00:29:59.154621 | 
2026-01-22 00:29:59.154746 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-22 00:29:59.182655 | localhost | ok
2026-01-22 00:29:59.191334 | 
2026-01-22 00:29:59.191456 | TASK [emit-job-header : Print job information]
2026-01-22 00:29:59.219995 | # Job Information
2026-01-22 00:29:59.220238 | Ansible Version: 2.15.12
2026-01-22 00:29:59.220276 | Job: ansible-test-sanity-docker-stable-2.10
2026-01-22 00:29:59.220299 | Pipeline: periodic
2026-01-22 00:29:59.220319 | Executor: ze03.softwarefactory-project.io
2026-01-22 00:29:59.220336 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-01-22 00:29:59.220357 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/37e/ansible/37e731cd1d6c4b7daccf126d7ec9b752/
2026-01-22 00:29:59.220376 | Event ID: 332d988a0e394ca48f64b1f395ee0e08
2026-01-22 00:29:59.224263 | 
2026-01-22 00:29:59.224361 | LOOP [emit-job-header : Print node information]
2026-01-22 00:29:59.334926 | localhost | ok:
2026-01-22 00:29:59.335117 | localhost | # Node Information
2026-01-22 00:29:59.335491 | localhost | Inventory Hostname: controller
2026-01-22 00:29:59.335530 | localhost | Hostname: np0005591463
2026-01-22 00:29:59.335563 | localhost | Username: zuul
2026-01-22 00:29:59.335600 | localhost | Distro: Fedora 37
2026-01-22 00:29:59.335631 | localhost | Provider: ansible-vexxhost-ams1
2026-01-22 00:29:59.335661 | localhost | Region: ams1
2026-01-22 00:29:59.335689 | localhost | Label: ansible-fedora-37-1vcpu
2026-01-22 00:29:59.335717 | localhost | Product Name: OpenStack Nova
2026-01-22 00:29:59.335773 | localhost | Interface IP: 38.129.16.64
2026-01-22 00:29:59.352060 | 
2026-01-22 00:29:59.352204 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-22 00:29:59.780837 | localhost -> localhost | changed
2026-01-22 00:29:59.793427 | 
2026-01-22 00:29:59.793549 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-22 00:30:00.809661 | localhost -> localhost | changed
2026-01-22 00:30:00.840615 | 
2026-01-22 00:30:00.840705 | PLAY [all:!appliance*]
2026-01-22 00:30:00.858327 | 
2026-01-22 00:30:00.858452 | TASK [include_role : start-zuul-console]
2026-01-22 00:30:00.879611 | controller | ok
2026-01-22 00:30:00.896884 | 
2026-01-22 00:30:00.897244 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-22 00:30:02.024243 | controller | ok
2026-01-22 00:30:02.037887 | 
2026-01-22 00:30:02.038024 | TASK [use-our-mirror : Retrieve the IP address]
2026-01-22 00:30:05.044515 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-01-22 00:30:05.051722 | 
2026-01-22 00:30:05.051867 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-01-22 00:30:05.411077 | controller | skipping: Conditional result was False
2026-01-22 00:30:05.420847 | 
2026-01-22 00:30:05.421052 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-01-22 00:30:05.447404 | controller | skipping: Conditional result was False
2026-01-22 00:30:05.457454 | 
2026-01-22 00:30:05.457613 | TASK [use-our-mirror : Create the podman configuration directory]
2026-01-22 00:30:05.483749 | controller | skipping: Conditional result was False
2026-01-22 00:30:05.493615 | 
2026-01-22 00:30:05.493803 | TASK [use-our-mirror : Copy the podman configuration]
2026-01-22 00:30:05.520221 | controller | skipping: Conditional result was False
2026-01-22 00:30:05.528924 | 
2026-01-22 00:30:05.529083 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-01-22 00:30:05.545336 | controller | skipping: Conditional result was False
2026-01-22 00:30:05.556774 | 
2026-01-22 00:30:05.556924 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-01-22 00:30:05.578756 | controller | skipping: Conditional result was False
2026-01-22 00:30:05.591782 | 
2026-01-22 00:30:05.591930 | TASK [Disable Fedora Modular]
2026-01-22 00:30:06.922888 | controller | changed
2026-01-22 00:30:06.938124 | 
2026-01-22 00:30:06.938347 | TASK [Enable EPEL]
2026-01-22 00:30:06.966616 | controller | skipping: Conditional result was False
2026-01-22 00:30:06.973505 | 
2026-01-22 00:30:06.973585 | TASK [Register the RHEL node]
2026-01-22 00:30:07.406159 | 
2026-01-22 00:30:07.406393 | TASK [Show the subscription-manager status]
2026-01-22 00:30:07.768106 | controller | skipping: Conditional result was False
2026-01-22 00:30:07.777266 | 
2026-01-22 00:30:07.777394 | TASK [Enable EPEL on RHEL]
2026-01-22 00:30:08.154420 | controller | skipping: Conditional result was False
2026-01-22 00:30:08.161301 | 
2026-01-22 00:30:08.161432 | TASK [Install git and tox]
2026-01-22 00:33:13.189338 | controller | changed
2026-01-22 00:33:13.196022 | 
2026-01-22 00:33:13.196106 | TASK [include_role : prepare-workspace]
2026-01-22 00:33:13.217233 | controller | ok
2026-01-22 00:33:13.239463 | 
2026-01-22 00:33:13.239589 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-22 00:33:14.066589 | controller | ok
2026-01-22 00:33:14.082872 | 
2026-01-22 00:33:14.083046 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-22 00:33:30.820218 | controller | Output suppressed because no_log was given
2026-01-22 00:33:30.836340 | 
2026-01-22 00:33:30.836466 | TASK [include_role : prepare-workspace-openshift]
2026-01-22 00:33:30.853916 | controller | skipping: Conditional result was False
2026-01-22 00:33:30.886406 | 
2026-01-22 00:33:30.886545 | PLAY [all:!appliance]
2026-01-22 00:33:30.912036 | 
2026-01-22 00:33:30.912341 | TASK [Run add-build-sshkey role (RSA)]
2026-01-22 00:33:30.937048 | controller | ok
2026-01-22 00:33:30.958889 | 
2026-01-22 00:33:30.959017 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-22 00:33:31.218692 | controller -> localhost | ok
2026-01-22 00:33:31.226263 | 
2026-01-22 00:33:31.226343 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-22 00:33:31.259151 | controller | ok
2026-01-22 00:33:31.274073 | controller | included: /var/lib/zuul/builds/37e731cd1d6c4b7daccf126d7ec9b752/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-22 00:33:31.280075 | 
2026-01-22 00:33:31.280141 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-22 00:33:31.887982 | controller -> localhost | Generating public/private rsa key pair.
2026-01-22 00:33:31.888316 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/37e731cd1d6c4b7daccf126d7ec9b752/work/37e731cd1d6c4b7daccf126d7ec9b752_id_rsa.
2026-01-22 00:33:31.888359 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/37e731cd1d6c4b7daccf126d7ec9b752/work/37e731cd1d6c4b7daccf126d7ec9b752_id_rsa.pub.
2026-01-22 00:33:31.888389 | controller -> localhost | The key fingerprint is:
2026-01-22 00:33:31.888417 | controller -> localhost | SHA256:kHmxLsM394deniARzszFAG+nf5pxUogfy70tjBU3Pbw zuul-build-sshkey
2026-01-22 00:33:31.888444 | controller -> localhost | The key's randomart image is:
2026-01-22 00:33:31.888472 | controller -> localhost | +---[RSA 2048]----+
2026-01-22 00:33:31.888497 | controller -> localhost | |        o..      |
2026-01-22 00:33:31.888525 | controller -> localhost | |       o + o     |
2026-01-22 00:33:31.888551 | controller -> localhost | |      + o + + . .|
2026-01-22 00:33:31.888577 | controller -> localhost | |     . + * * ..+o|
2026-01-22 00:33:31.888610 | controller -> localhost | |      + S X o .o+|
2026-01-22 00:33:31.888668 | controller -> localhost | |       + o * *.E |
2026-01-22 00:33:31.888704 | controller -> localhost | |          . @+*  |
2026-01-22 00:33:31.888780 | controller -> localhost | |           o.%o+ |
2026-01-22 00:33:31.888826 | controller -> localhost | |            + +..|
2026-01-22 00:33:31.888896 | controller -> localhost | +----[SHA256]-----+
2026-01-22 00:33:31.888974 | controller -> localhost | ok: Runtime: 0:00:00.134033
2026-01-22 00:33:31.900146 | 
2026-01-22 00:33:31.900285 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-22 00:33:31.925381 | controller | ok
2026-01-22 00:33:31.936800 | controller | included: /var/lib/zuul/builds/37e731cd1d6c4b7daccf126d7ec9b752/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-22 00:33:31.947334 | 
2026-01-22 00:33:31.947467 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-22 00:33:31.972244 | controller | skipping: Conditional result was False
2026-01-22 00:33:31.979974 | 
2026-01-22 00:33:31.980094 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-22 00:33:33.104613 | controller | changed
2026-01-22 00:33:33.111676 | 
2026-01-22 00:33:33.111808 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-22 00:33:33.757257 | controller | ok
2026-01-22 00:33:33.765583 | 
2026-01-22 00:33:33.765752 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-22 00:33:36.765369 | controller | changed
2026-01-22 00:33:36.772885 | 
2026-01-22 00:33:36.772982 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-22 00:33:39.751349 | controller | changed
2026-01-22 00:33:39.760628 | 
2026-01-22 00:33:39.760810 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-22 00:33:39.787664 | controller | skipping: Conditional result was False
2026-01-22 00:33:39.795122 | 
2026-01-22 00:33:39.795268 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-22 00:33:40.241214 | controller -> localhost | changed
2026-01-22 00:33:40.269553 | 
2026-01-22 00:33:40.269907 | TASK [add-build-sshkey : Add back temp key]
2026-01-22 00:33:40.595275 | controller -> localhost | Identity added: /var/lib/zuul/builds/37e731cd1d6c4b7daccf126d7ec9b752/work/37e731cd1d6c4b7daccf126d7ec9b752_id_rsa (zuul-build-sshkey)
2026-01-22 00:33:40.595754 | controller -> localhost | ok: Runtime: 0:00:00.016322
2026-01-22 00:33:40.611593 | 
2026-01-22 00:33:40.611773 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-22 00:33:41.551885 | controller | ok
2026-01-22 00:33:41.567781 | 
2026-01-22 00:33:41.567999 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-22 00:33:41.595244 | controller | skipping: Conditional result was False
2026-01-22 00:33:41.621952 | 
2026-01-22 00:33:41.622086 | TASK [Run add-build-sshkey role (ECDSA)]
2026-01-22 00:33:41.644923 | controller | ok
2026-01-22 00:33:41.665445 | 
2026-01-22 00:33:41.665575 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-22 00:33:41.885820 | controller -> localhost | ok
2026-01-22 00:33:41.895652 | 
2026-01-22 00:33:41.895804 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-22 00:33:41.922845 | controller | ok
2026-01-22 00:33:41.939671 | controller | included: /var/lib/zuul/builds/37e731cd1d6c4b7daccf126d7ec9b752/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-22 00:33:41.948053 | 
2026-01-22 00:33:41.948151 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-22 00:33:42.279057 | controller -> localhost | Generating public/private ecdsa key pair.
2026-01-22 00:33:42.279288 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/37e731cd1d6c4b7daccf126d7ec9b752/work/37e731cd1d6c4b7daccf126d7ec9b752_id_ecdsa.
2026-01-22 00:33:42.279318 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/37e731cd1d6c4b7daccf126d7ec9b752/work/37e731cd1d6c4b7daccf126d7ec9b752_id_ecdsa.pub.
2026-01-22 00:33:42.279350 | controller -> localhost | The key fingerprint is:
2026-01-22 00:33:42.279371 | controller -> localhost | SHA256:971nYcss990yUYawuog3UNXBADh58rZ4BdfRRb/l3lE zuul-build-sshkey
2026-01-22 00:33:42.279390 | controller -> localhost | The key's randomart image is:
2026-01-22 00:33:42.279408 | controller -> localhost | +---[ECDSA 521]---+
2026-01-22 00:33:42.279425 | controller -> localhost | |       o...=o+ oo|
2026-01-22 00:33:42.279442 | controller -> localhost | |      = o o = . .|
2026-01-22 00:33:42.279459 | controller -> localhost | |       = +   o .E|
2026-01-22 00:33:42.279475 | controller -> localhost | |        + . . ..*|
2026-01-22 00:33:42.279492 | controller -> localhost | |       +So..   =.|
2026-01-22 00:33:42.279509 | controller -> localhost | |      o o... ..+o|
2026-01-22 00:33:42.279534 | controller -> localhost | |       + . .. =.=|
2026-01-22 00:33:42.279552 | controller -> localhost | |      . + .  .o*=|
2026-01-22 00:33:42.279569 | controller -> localhost | |       . .    +==|
2026-01-22 00:33:42.279585 | controller -> localhost | +----[SHA256]-----+
2026-01-22 00:33:42.279634 | controller -> localhost | ok: Runtime: 0:00:00.013037
2026-01-22 00:33:42.289117 | 
2026-01-22 00:33:42.289250 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-22 00:33:42.359887 | controller | ok
2026-01-22 00:33:42.368536 | controller | included: /var/lib/zuul/builds/37e731cd1d6c4b7daccf126d7ec9b752/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-22 00:33:42.379998 | 
2026-01-22 00:33:42.380118 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-22 00:33:42.404631 | controller | skipping: Conditional result was False
2026-01-22 00:33:42.415052 | 
2026-01-22 00:33:42.415195 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-22 00:33:43.298617 | controller | changed
2026-01-22 00:33:43.310308 | 
2026-01-22 00:33:43.310456 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-22 00:33:43.950139 | controller | ok
2026-01-22 00:33:43.958327 | 
2026-01-22 00:33:43.958459 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-22 00:33:46.930281 | controller | changed
2026-01-22 00:33:46.943226 | 
2026-01-22 00:33:46.943375 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-22 00:33:49.911449 | controller | changed
2026-01-22 00:33:49.920541 | 
2026-01-22 00:33:49.920674 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-22 00:33:49.945968 | controller | skipping: Conditional result was False
2026-01-22 00:33:49.954612 | 
2026-01-22 00:33:49.954704 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-22 00:33:50.205238 | controller -> localhost | changed
2026-01-22 00:33:50.219646 | 
2026-01-22 00:33:50.219765 | TASK [add-build-sshkey : Add back temp key]
2026-01-22 00:33:50.498415 | controller -> localhost | Identity added: /var/lib/zuul/builds/37e731cd1d6c4b7daccf126d7ec9b752/work/37e731cd1d6c4b7daccf126d7ec9b752_id_ecdsa (zuul-build-sshkey)
2026-01-22 00:33:50.498782 | controller -> localhost | ok: Runtime: 0:00:00.013500
2026-01-22 00:33:50.513928 | 
2026-01-22 00:33:50.514037 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-22 00:33:51.211577 | controller | ok
2026-01-22 00:33:51.219364 | 
2026-01-22 00:33:51.219490 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-22 00:33:51.244431 | controller | skipping: Conditional result was False
2026-01-22 00:33:51.257670 | 
2026-01-22 00:33:51.257820 | TASK [include_role : remove-zuul-sshkey]
2026-01-22 00:33:51.272277 | controller | skipping: Conditional result was False
2026-01-22 00:33:51.280097 | 
2026-01-22 00:33:51.280183 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-22 00:33:51.888529 | controller | ok: "logs"
2026-01-22 00:33:51.888842 | controller | ok: All items complete
2026-01-22 00:33:51.888880 | 
2026-01-22 00:33:52.510561 | controller | ok: "artifacts"
2026-01-22 00:33:53.127542 | controller | ok: "docs"
2026-01-22 00:33:53.169844 | 
2026-01-22 00:33:53.170045 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-22 00:33:53.847200 | controller | changed: "logs"
2026-01-22 00:33:54.451516 | controller | changed: "artifacts"
2026-01-22 00:33:55.073586 | controller | changed: "docs"
2026-01-22 00:33:55.138237 | 
2026-01-22 00:33:55.138364 | PLAY RECAP
2026-01-22 00:33:55.138414 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-01-22 00:33:55.138449 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-22 00:33:55.138470 | 
2026-01-22 00:33:55.268673 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-22 00:33:55.269617 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-22 00:33:55.953984 | 
2026-01-22 00:33:55.954174 | PLAY [all]
2026-01-22 00:33:55.978176 | 
2026-01-22 00:33:55.978329 | TASK [Install binary dependencies]
2026-01-22 00:33:56.029981 | controller | ok
2026-01-22 00:33:56.052029 | 
2026-01-22 00:33:56.052191 | TASK [bindep : Include find tasks]
2026-01-22 00:33:56.084803 | controller | ok
2026-01-22 00:33:56.096958 | controller | included: /var/lib/zuul/builds/37e731cd1d6c4b7daccf126d7ec9b752/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-01-22 00:33:56.107070 | 
2026-01-22 00:33:56.107219 | TASK [bindep : Look for bindep.txt]
2026-01-22 00:33:57.038350 | controller | ok
2026-01-22 00:33:57.051325 | 
2026-01-22 00:33:57.051487 | TASK [bindep : Define bindep_file fact]
2026-01-22 00:33:57.079236 | controller | skipping: Conditional result was False
2026-01-22 00:33:57.090584 | 
2026-01-22 00:33:57.090821 | TASK [bindep : Look for other-requirements.txt]
2026-01-22 00:33:57.731910 | controller | ok
2026-01-22 00:33:57.745973 | 
2026-01-22 00:33:57.746119 | TASK [bindep : Define bindep_file fact]
2026-01-22 00:33:57.781840 | controller | skipping: Conditional result was False
2026-01-22 00:33:57.791518 | 
2026-01-22 00:33:57.791663 | TASK [bindep : Look for bindep fallback file]
2026-01-22 00:33:57.818060 | controller | skipping: Conditional result was False
2026-01-22 00:33:57.825490 | 
2026-01-22 00:33:57.825631 | TASK [bindep : Define bindep_file fact]
2026-01-22 00:33:57.851265 | controller | skipping: Conditional result was False
2026-01-22 00:33:57.862294 | 
2026-01-22 00:33:57.862397 | TASK [bindep : Include bindep tasks]
2026-01-22 00:33:57.895404 | controller | skipping: Conditional result was False
2026-01-22 00:33:57.906504 | 
2026-01-22 00:33:57.906671 | TASK [bindep : Include install tasks]
2026-01-22 00:33:57.942077 | controller | skipping: Conditional result was False
2026-01-22 00:33:57.951704 | 
2026-01-22 00:33:57.952298 | LOOP [bindep : Include package tasks]
2026-01-22 00:33:58.041607 | 
2026-01-22 00:33:58.041808 | TASK [Run test-setup role]
2026-01-22 00:33:58.071005 | controller | ok
2026-01-22 00:33:58.091398 | 
2026-01-22 00:33:58.091534 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-01-22 00:33:58.727428 | controller | ok
2026-01-22 00:33:58.742250 | 
2026-01-22 00:33:58.742432 | TASK [test-setup : Run tools/test-setup.sh]
2026-01-22 00:33:59.122407 | controller | skipping: Conditional result was False
2026-01-22 00:33:59.165116 | 
2026-01-22 00:33:59.165250 | PLAY RECAP
2026-01-22 00:33:59.165304 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-22 00:33:59.165330 | 
2026-01-22 00:33:59.319329 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-22 00:33:59.320493 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-22 00:34:01.115982 | 
2026-01-22 00:34:01.116128 | PLAY [controller]
2026-01-22 00:34:01.138260 | 
2026-01-22 00:34:01.138414 | TASK [Create the /root directory]
2026-01-22 00:34:02.162172 | controller | ok
2026-01-22 00:34:02.168965 | 
2026-01-22 00:34:02.169124 | TASK [Install glibc-langpack-en]
2026-01-22 00:34:09.857110 | controller | ok: Nothing to do
2026-01-22 00:34:09.870193 | 
2026-01-22 00:34:09.870375 | TASK [Ensure controller directory exists]
2026-01-22 00:34:10.700054 | controller | changed
2026-01-22 00:34:10.708415 | 
2026-01-22 00:34:10.708518 | TASK [Install container runtime]
2026-01-22 00:34:10.766366 | controller | ok
2026-01-22 00:34:10.820589 | 
2026-01-22 00:34:10.820747 | LOOP [ensure-docker : Gather variables for each operating system]
2026-01-22 00:34:10.859338 | controller | ok: "/var/lib/zuul/builds/37e731cd1d6c4b7daccf126d7ec9b752/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2026-01-22 00:34:10.877234 | 
2026-01-22 00:34:10.877400 | TASK [ensure-docker : Sanity check for distro selection]
2026-01-22 00:34:10.893003 | controller | skipping: Conditional result was False
2026-01-22 00:34:10.899536 | 
2026-01-22 00:34:10.899632 | TASK [ensure-docker : Sanity check for distro install]
2026-01-22 00:34:10.923662 | controller | skipping: Conditional result was False
2026-01-22 00:34:10.930656 | 
2026-01-22 00:34:10.930755 | TASK [ensure-docker : Sanity check for upstream install]
2026-01-22 00:34:10.954593 | controller | skipping: Conditional result was False
2026-01-22 00:34:10.962810 | 
2026-01-22 00:34:10.962888 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2026-01-22 00:34:10.987902 | controller | skipping: Conditional result was False
2026-01-22 00:34:10.997944 | 
2026-01-22 00:34:10.998059 | TASK [ensure-docker : Include tasks for docker install]
2026-01-22 00:34:11.021536 | controller | ok
2026-01-22 00:34:11.034816 | controller | included: /var/lib/zuul/builds/37e731cd1d6c4b7daccf126d7ec9b752/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2026-01-22 00:34:11.050109 | 
2026-01-22 00:34:11.050327 | TASK [ensure-docker : Not Implemented]
2026-01-22 00:34:11.066362 | controller | skipping: Conditional result was False
2026-01-22 00:34:11.074013 | 
2026-01-22 00:34:11.074092 | TASK [Set up docker mirrors]
2026-01-22 00:34:11.094343 | controller | ok
2026-01-22 00:34:11.115410 | 
2026-01-22 00:34:11.115528 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2026-01-22 00:34:11.149961 | controller | skipping: Conditional result was False
2026-01-22 00:34:11.156895 | 
2026-01-22 00:34:11.157041 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2026-01-22 00:34:11.181196 | controller | skipping: Conditional result was False
2026-01-22 00:34:11.191154 | 
2026-01-22 00:34:11.191300 | TASK [use-docker-mirror : Set docker_mirror fact]
2026-01-22 00:34:11.216132 | controller | skipping: Conditional result was False
2026-01-22 00:34:11.258773 | 
2026-01-22 00:34:11.258922 | TASK [use-docker-mirror : Set up docker mirrors]
2026-01-22 00:34:11.283372 | controller | skipping: Conditional result was False
2026-01-22 00:34:11.300773 | 
2026-01-22 00:34:11.300923 | TASK [Add all repositories]
2026-01-22 00:34:11.324407 | controller | ok
2026-01-22 00:34:11.354758 | 
2026-01-22 00:34:11.354883 | LOOP [ensure-package-repositories : Install repositories]
2026-01-22 00:34:11.377651 | controller | ok: "/var/lib/zuul/builds/37e731cd1d6c4b7daccf126d7ec9b752/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2026-01-22 00:34:11.377847 | controller | ok: All items complete
2026-01-22 00:34:11.377879 | 
2026-01-22 00:34:11.387769 | controller | included: /var/lib/zuul/builds/37e731cd1d6c4b7daccf126d7ec9b752/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2026-01-22 00:34:11.395142 | 
2026-01-22 00:34:11.395257 | TASK [ensure-package-repositories : Create GPG key temp directory]
2026-01-22 00:34:12.342949 | controller | changed
2026-01-22 00:34:12.358645 | 
2026-01-22 00:34:12.358868 | LOOP [ensure-package-repositories : Upload GPG keys]
2026-01-22 00:34:15.494410 | controller | changed:
2026-01-22 00:34:15.495032 | controller | {
2026-01-22 00:34:15.495129 | 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"
2026-01-22 00:34:15.495224 | controller | }
2026-01-22 00:34:15.516258 | 
2026-01-22 00:34:15.516406 | LOOP [ensure-package-repositories : Import GPG keys]
2026-01-22 00:34:16.763866 | controller | changed:
2026-01-22 00:34:16.764110 | controller | {
2026-01-22 00:34:16.764148 | 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"
2026-01-22 00:34:16.764181 | controller | }
2026-01-22 00:34:16.764219 | controller | changed: All items complete
2026-01-22 00:34:16.764247 | 
2026-01-22 00:34:16.778176 | 
2026-01-22 00:34:16.778329 | TASK [ensure-package-repositories : Remove GPG temp directory]
2026-01-22 00:34:17.490817 | controller | changed
2026-01-22 00:34:17.500703 | 
2026-01-22 00:34:17.500822 | LOOP [ensure-package-repositories : Add repositories]
2026-01-22 00:34:18.335558 | controller | changed:
2026-01-22 00:34:18.335843 | controller | {
2026-01-22 00:34:18.335899 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2026-01-22 00:34:18.335942 | controller |   "description": "Docker CE Stable - $basearch",
2026-01-22 00:34:18.335979 | controller |   "gpgcheck": true,
2026-01-22 00:34:18.336016 | controller |   "name": "docker-ce-stable"
2026-01-22 00:34:18.336051 | controller | }
2026-01-22 00:34:18.353886 | 
2026-01-22 00:34:18.353970 | TASK [ensure-docker : Install pre-reqs]
2026-01-22 00:34:23.146356 | controller | ok: Nothing to do
2026-01-22 00:34:23.161622 | 
2026-01-22 00:34:23.161872 | TASK [ensure-docker : Remove packages]
2026-01-22 00:34:26.201508 | controller | ok: Nothing to do
2026-01-22 00:34:26.213938 | 
2026-01-22 00:34:26.214098 | TASK [ensure-docker : Install upstream docker using package]
2026-01-22 00:35:46.951781 | controller | changed
2026-01-22 00:35:46.958991 | 
2026-01-22 00:35:46.959109 | TASK [ensure-docker : Setup docker]
2026-01-22 00:35:46.978938 | controller | ok
2026-01-22 00:35:46.990472 | controller | included: /var/lib/zuul/builds/37e731cd1d6c4b7daccf126d7ec9b752/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2026-01-22 00:35:47.003178 | 
2026-01-22 00:35:47.003288 | TASK [ensure-docker : Ensure "docker" group exists]
2026-01-22 00:35:47.960696 | controller | ok
2026-01-22 00:35:47.966399 | 
2026-01-22 00:35:47.966474 | TASK [ensure-docker : Add user to docker group]
2026-01-22 00:35:49.044818 | controller | changed
2026-01-22 00:35:49.058337 | 
2026-01-22 00:35:49.058501 | TASK [Update docker daemon proxy configuration]
2026-01-22 00:35:49.085238 | controller | skipping: Conditional result was False
2026-01-22 00:35:49.093828 | 
2026-01-22 00:35:49.093957 | TASK [Lower default MTU]
2026-01-22 00:35:49.108039 | controller | skipping: Conditional result was False
2026-01-22 00:35:49.117329 | 
2026-01-22 00:35:49.117457 | TASK [ensure-docker : Get the status of the docket.socket unit]
2026-01-22 00:35:49.841937 | controller | inactive
2026-01-22 00:35:49.976003 | controller | ok: Runtime: 0:00:00.016126
2026-01-22 00:35:49.982111 | 
2026-01-22 00:35:49.982208 | TASK [ensure-docker : Get the status of the docket unit]
2026-01-22 00:35:50.629714 | controller | inactive
2026-01-22 00:35:50.843816 | controller | ok: Runtime: 0:00:00.025233
2026-01-22 00:35:50.850297 | 
2026-01-22 00:35:50.850370 | TASK [ensure-docker : Restart docker]
2026-01-22 00:35:50.901474 | Trigger a Docker restart
2026-01-22 00:35:50.908055 | 
2026-01-22 00:35:50.908128 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2026-01-22 00:35:50.948048 | 
2026-01-22 00:35:50.948206 | TASK [ensure-docker : Install docker-compose]
2026-01-22 00:35:50.973345 | controller | skipping: Conditional result was False
2026-01-22 00:35:50.983359 | 
2026-01-22 00:35:50.983513 | TASK [ensure-docker : Flush handlers before role exit]
2026-01-22 00:35:50.999642 | 
2026-01-22 00:35:50.999787 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2026-01-22 00:35:54.284456 | controller | changed
2026-01-22 00:35:54.295377 | 
2026-01-22 00:35:54.295460 | TASK [ensure-docker : Assure docker service is running]
2026-01-22 00:35:57.990571 | controller | changed
2026-01-22 00:35:58.001597 | 
2026-01-22 00:35:58.001704 | TASK [ensure-docker : Assure docker.socket service is running]
2026-01-22 00:35:59.858564 | controller | ok
2026-01-22 00:35:59.875243 | 
2026-01-22 00:35:59.875391 | TASK [ensure-docker : Correct group ownership on docker sock]
2026-01-22 00:36:00.805863 | controller | ok
2026-01-22 00:36:00.823570 | 
2026-01-22 00:36:00.823844 | TASK [ensure-docker : Validate ability to talk with docker]
2026-01-22 00:36:01.436291 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2026-01-22 00:36:01.691866 | controller | ok: Runtime: 0:00:00.040854
2026-01-22 00:36:01.700818 | 
2026-01-22 00:36:01.700990 | TASK [ensure-docker : Get version details]
2026-01-22 00:36:02.327437 | controller | Client: Docker Engine - Community
2026-01-22 00:36:02.329208 | controller |  Version:           24.0.7
2026-01-22 00:36:02.329625 | controller |  API version:       1.43
2026-01-22 00:36:02.329641 | controller |  Go version:        go1.20.10
2026-01-22 00:36:02.329662 | controller |  Git commit:        afdd53b
2026-01-22 00:36:02.329703 | controller |  Built:             Thu Oct 26 09:09:39 2023
2026-01-22 00:36:02.329712 | controller |  OS/Arch:           linux/amd64
2026-01-22 00:36:02.329720 | controller |  Context:           default
2026-01-22 00:36:02.329730 | controller |
2026-01-22 00:36:02.329737 | controller | Server: Docker Engine - Community
2026-01-22 00:36:02.329743 | controller |  Engine:
2026-01-22 00:36:02.329749 | controller |   Version:          24.0.7
2026-01-22 00:36:02.329756 | controller |   API version:      1.43 (minimum version 1.12)
2026-01-22 00:36:02.329762 | controller |   Go version:       go1.20.10
2026-01-22 00:36:02.329768 | controller |   Git commit:       311b9ff
2026-01-22 00:36:02.329774 | controller |   Built:            Thu Oct 26 09:08:11 2023
2026-01-22 00:36:02.329781 | controller |   OS/Arch:          linux/amd64
2026-01-22 00:36:02.329787 | controller |   Experimental:     false
2026-01-22 00:36:02.329794 | controller |  containerd:
2026-01-22 00:36:02.329800 | controller |   Version:          1.6.25
2026-01-22 00:36:02.329806 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2026-01-22 00:36:02.329812 | controller |  runc:
2026-01-22 00:36:02.329818 | controller |   Version:          1.1.10
2026-01-22 00:36:02.329824 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2026-01-22 00:36:02.329834 | controller |  docker-init:
2026-01-22 00:36:02.329860 | controller |   Version:          0.19.0
2026-01-22 00:36:02.329868 | controller |   GitCommit:        de40ad0
2026-01-22 00:36:02.569117 | controller | ok: Runtime: 0:00:00.049065
2026-01-22 00:36:02.583814 | 
2026-01-22 00:36:02.584005 | TASK [ensure-docker : Dump installed docker details]
2026-01-22 00:36:02.629570 | {'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': '2026-01-22 00:36:02.281231', 'end': '2026-01-22 00:36:02.330296', 'delta': '0:00:00.049065', 'msg': '', 'zuul_log_id': 'fa163ef9-e89a-14f4-8c0b-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}
2026-01-22 00:36:02.644147 | 
2026-01-22 00:36:02.644292 | TASK [Use buildset registry]
2026-01-22 00:36:02.670695 | controller | skipping: Conditional result was False
2026-01-22 00:36:02.688381 | 
2026-01-22 00:36:02.688605 | TASK [Ensure python3.8 is present]
2026-01-22 00:36:02.713674 | controller | skipping: Conditional result was False
2026-01-22 00:36:02.723468 | 
2026-01-22 00:36:02.723583 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-01-22 00:36:02.748967 | controller | ok
2026-01-22 00:36:02.777829 | 
2026-01-22 00:36:02.777953 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-01-22 00:36:08.673162 | controller | ok: Nothing to do
2026-01-22 00:36:08.680683 | 
2026-01-22 00:36:08.680809 | TASK [our-ensure-python : Also install python3-devel]
2026-01-22 00:36:35.483359 | controller | changed
2026-01-22 00:36:35.499618 | 
2026-01-22 00:36:35.499784 | TASK [Run ensure-virtualenv role]
2026-01-22 00:36:35.521630 | controller | ok
2026-01-22 00:36:35.547744 | 
2026-01-22 00:36:35.547890 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-01-22 00:36:36.280485 | controller | /usr/bin/virtualenv
2026-01-22 00:36:36.407096 | controller | ok: Runtime: 0:00:00.004711
2026-01-22 00:36:36.417976 | 
2026-01-22 00:36:36.418137 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-01-22 00:36:36.440756 | controller | skipping: Conditional result was False
2026-01-22 00:36:36.441239 | controller | changed: All items complete
2026-01-22 00:36:36.441299 | 
2026-01-22 00:36:36.471409 | 
2026-01-22 00:36:36.471612 | TASK [Find the full path of the Python interpreter]
2026-01-22 00:36:37.079759 | controller | /usr/bin/python3.8
2026-01-22 00:36:37.339196 | controller | ok
2026-01-22 00:36:37.347096 | 
2026-01-22 00:36:37.347260 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-01-22 00:36:39.777919 | controller | created virtual environment CPython3.8.18.final.0-64 in 1253ms
2026-01-22 00:36:39.897515 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-01-22 00:36:39.897577 | 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)
2026-01-22 00:36:39.897596 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-01-22 00:36:39.897615 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-01-22 00:36:40.206008 | controller | changed
2026-01-22 00:36:40.213127 | 
2026-01-22 00:36:40.213242 | TASK [Set selinux package]
2026-01-22 00:36:40.251716 | controller | ok
2026-01-22 00:36:40.263349 | 
2026-01-22 00:36:40.263546 | TASK [Set selinux package (Fedora)]
2026-01-22 00:36:40.317465 | controller | ok
2026-01-22 00:36:40.323206 | 
2026-01-22 00:36:40.323298 | TASK [Install selinux into virtualenv]
2026-01-22 00:36:46.134332 | controller | Collecting selinux-please-lie-to-me
2026-01-22 00:36:46.379279 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-01-22 00:36:47.581596 | controller | Collecting setuptools<50.0.0
2026-01-22 00:36:47.587251 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-01-22 00:36:47.739923 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 5.5 MB/s eta 0:00:00
2026-01-22 00:36:47.860912 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-01-22 00:36:47.861565 | controller |   Attempting uninstall: setuptools
2026-01-22 00:36:47.861590 | controller |     Found existing installation: setuptools 62.6.0
2026-01-22 00:36:47.980016 | controller |     Uninstalling setuptools-62.6.0:
2026-01-22 00:36:47.993658 | controller |       Successfully uninstalled setuptools-62.6.0
2026-01-22 00:36:48.579016 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-01-22 00:36:48.758958 | controller |
2026-01-22 00:36:49.010290 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-01-22 00:36:49.010349 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-01-22 00:36:49.207053 | controller | ok: Runtime: 0:00:07.528952
2026-01-22 00:36:49.213948 | 
2026-01-22 00:36:49.214034 | TASK [Install pytest-forked into virtualenv]
2026-01-22 00:36:50.928188 | controller | Collecting pytest-forked
2026-01-22 00:36:50.982273 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-01-22 00:36:51.046238 | controller | Collecting py
2026-01-22 00:36:51.053621 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-01-22 00:36:51.081223 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 3.8 MB/s eta 0:00:00
2026-01-22 00:36:51.282037 | controller | Collecting pytest>=3.10
2026-01-22 00:36:51.288357 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2026-01-22 00:36:51.306224 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 23.1 MB/s eta 0:00:00
2026-01-22 00:36:51.377047 | controller | Collecting exceptiongroup>=1.0.0rc8
2026-01-22 00:36:51.384537 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2026-01-22 00:36:51.466183 | controller | Collecting tomli>=1
2026-01-22 00:36:51.474238 | controller |   Downloading tomli-2.4.0-py3-none-any.whl (14 kB)
2026-01-22 00:36:51.508655 | controller | Collecting iniconfig
2026-01-22 00:36:51.513708 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2026-01-22 00:36:51.567042 | controller | Collecting pluggy<2,>=1.5
2026-01-22 00:36:51.573177 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2026-01-22 00:36:51.644978 | controller | Collecting packaging
2026-01-22 00:36:51.649827 | controller |   Downloading packaging-26.0-py3-none-any.whl (74 kB)
2026-01-22 00:36:51.665329 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 5.3 MB/s eta 0:00:00
2026-01-22 00:36:51.736745 | controller | Collecting typing-extensions>=4.6.0
2026-01-22 00:36:51.741079 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2026-01-22 00:36:51.750352 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 6.2 MB/s eta 0:00:00
2026-01-22 00:36:51.848808 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2026-01-22 00:36:52.630962 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-26.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.4.0 typing-extensions-4.13.2
2026-01-22 00:36:52.636947 | controller |
2026-01-22 00:36:53.006482 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-01-22 00:36:53.006537 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-01-22 00:36:53.597076 | controller | ok: Runtime: 0:00:02.813503
2026-01-22 00:36:53.603513 | 
2026-01-22 00:36:53.603595 | TASK [Update pip]
2026-01-22 00:36:55.763769 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2026-01-22 00:36:56.197775 | controller | Collecting pip
2026-01-22 00:36:56.318348 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2026-01-22 00:36:56.479720 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 12.4 MB/s eta 0:00:00
2026-01-22 00:36:56.705088 | controller | Installing collected packages: pip
2026-01-22 00:36:56.705317 | controller |   Attempting uninstall: pip
2026-01-22 00:36:56.706305 | controller |     Found existing installation: pip 22.2.2
2026-01-22 00:36:57.188490 | controller |     Uninstalling pip-22.2.2:
2026-01-22 00:36:57.219228 | controller |       Successfully uninstalled pip-22.2.2
2026-01-22 00:36:59.946729 | controller | Successfully installed pip-25.0.1
2026-01-22 00:37:00.463070 | controller | ok: Runtime: 0:00:05.492014
2026-01-22 00:37:00.471425 | 
2026-01-22 00:37:00.471531 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-01-22 00:37:01.610015 | controller | changed
2026-01-22 00:37:01.622172 | 
2026-01-22 00:37:01.622334 | TASK [Install ansible into virtualenv]
2026-01-22 00:37:03.838855 | controller | Processing ./src/github.com/ansible/ansible
2026-01-22 00:37:03.840455 | controller |   Preparing metadata (setup.py): started
2026-01-22 00:37:04.687484 | controller |   Preparing metadata (setup.py): finished with status 'done'
2026-01-22 00:37:04.758695 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2026-01-22 00:37:04.775514 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2026-01-22 00:37:04.869193 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2026-01-22 00:37:04.875461 | 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)
2026-01-22 00:37:05.300365 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2026-01-22 00:37:05.306086 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2026-01-22 00:37:05.334275 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (26.0)
2026-01-22 00:37:05.558533 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2026-01-22 00:37:05.563956 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2026-01-22 00:37:05.576617 | 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)
2026-01-22 00:37:05.693119 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2026-01-22 00:37:05.701147 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2026-01-22 00:37:05.730196 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2026-01-22 00:37:05.734914 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2026-01-22 00:37:05.758403 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2026-01-22 00:37:05.812813 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 93.8 MB/s eta 0:00:00
2026-01-22 00:37:05.817570 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2026-01-22 00:37:05.831601 | controller | Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (806 kB)
2026-01-22 00:37:05.850166 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 40.6 MB/s eta 0:00:00
2026-01-22 00:37:05.856405 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2026-01-22 00:37:05.881987 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2026-01-22 00:37:05.894756 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2026-01-22 00:37:05.922095 | controller | Building wheels for collected packages: ansible-base
2026-01-22 00:37:05.923179 | controller |   Building wheel for ansible-base (setup.py): started
2026-01-22 00:37:07.958802 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2026-01-22 00:37:07.961936 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=b1f73de73f2a09e1f502cb5fcb6f2417668e4ee8c0d913fc9e51ba4a6dc54053
2026-01-22 00:37:07.962236 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-m5gtmdid/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2026-01-22 00:37:07.977028 | controller | Successfully built ansible-base
2026-01-22 00:37:08.027584 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2026-01-22 00:37:10.818122 | 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
2026-01-22 00:37:11.497802 | controller | ok: Runtime: 0:00:08.311411
2026-01-22 00:37:11.522983 | 
2026-01-22 00:37:11.523207 | TASK [Setup download-artifact-fork role]
2026-01-22 00:37:11.562160 | controller | ok
2026-01-22 00:37:11.587924 | 
2026-01-22 00:37:11.588110 | TASK [download-artifact-fork : Parse inventory response]
2026-01-22 00:37:11.633946 | controller | ok
2026-01-22 00:37:11.640661 | 
2026-01-22 00:37:11.640774 | TASK [download-artifact-fork : Fetch info from Zuul API]
2026-01-22 00:37:11.666013 | controller | skipping: Conditional result was False
2026-01-22 00:37:11.673424 | 
2026-01-22 00:37:11.673555 | TASK [download-artifact-fork : Parse build response]
2026-01-22 00:37:11.702965 | controller | skipping: Conditional result was False
2026-01-22 00:37:11.710337 | 
2026-01-22 00:37:11.710465 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2026-01-22 00:37:12.505657 | controller | changed
2026-01-22 00:37:12.516168 | 
2026-01-22 00:37:12.516260 | LOOP [download-artifact-fork : Download archive by type]
2026-01-22 00:37:12.542936 | controller | skipping: Conditional result was False
2026-01-22 00:37:12.543311 | 
2026-01-22 00:37:14.343415 | controller | changed: OK (20187 bytes)
2026-01-22 00:37:14.368277 | 
2026-01-22 00:37:14.368521 | TASK [deploy-artifacts : define __collections]
2026-01-22 00:37:14.398817 | controller | ok
2026-01-22 00:37:14.411936 | 
2026-01-22 00:37:14.412120 | LOOP [deploy-artifacts : Create list of collections]
2026-01-22 00:37:14.441251 | controller | skipping: Conditional result was False
2026-01-22 00:37:14.456169 | controller | ok:
2026-01-22 00:37:14.456296 | controller | {
2026-01-22 00:37:14.456322 | controller |   "branch": "main",
2026-01-22 00:37:14.456343 | controller |   "job": "build-ansible-collection",
2026-01-22 00:37:14.456362 | controller |   "metadata": {
2026-01-22 00:37:14.456381 | controller |     "type": "ansible_collection",
2026-01-22 00:37:14.456422 | controller |     "version": "5.0.0"
2026-01-22 00:37:14.456447 | controller |   },
2026-01-22 00:37:14.456470 | controller |   "name": "ansible.network",
2026-01-22 00:37:14.456489 | controller |   "project": "ansible-collections/ansible.network",
2026-01-22 00:37:14.456509 | controller |   "url": "https://e8a5b7081282fcac5d0d-aaed4513bc0475e9932dd89978f40d93.ssl.cf2.rackcdn.com/ansible/b852468f44bd41ce8569b23a5ca52b69/artifacts/ansible-network-5.0.0.tar.gz"
2026-01-22 00:37:14.456539 | controller | }
2026-01-22 00:37:14.472867 | 
2026-01-22 00:37:14.476820 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2026-01-22 00:37:15.717195 | controller | Starting galaxy collection install process
2026-01-22 00:37:15.717742 | controller | Process install dependency map
2026-01-22 00:37:28.090714 | controller | Starting collection install process
2026-01-22 00:37:28.090895 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2026-01-22 00:37:28.103996 | controller | ansible.network (5.0.0) was installed successfully
2026-01-22 00:37:28.104109 | controller | Installing 'ansible.netcommon:8.2.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-01-22 00:37:28.104294 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-8.2.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4077anzmy4h0/tmpic6whk_b
2026-01-22 00:37:29.929757 | controller | ansible.netcommon (8.2.1) was installed successfully
2026-01-22 00:37:29.929892 | controller | Installing 'ansible.utils:6.0.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-01-22 00:37:29.930084 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-utils-6.0.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4077anzmy4h0/tmpic6whk_b
2026-01-22 00:37:31.379650 | controller | ansible.utils (6.0.1) was installed successfully
2026-01-22 00:37:31.379822 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2026-01-22 00:37:31.379835 | 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-4077anzmy4h0/tmpic6whk_b
2026-01-22 00:37:33.884922 | controller | arista.eos (12.0.0) was installed successfully
2026-01-22 00:37:33.885047 | controller | Installing 'cisco.ios:11.2.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2026-01-22 00:37:33.885273 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.2.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4077anzmy4h0/tmpic6whk_b
2026-01-22 00:37:37.090719 | controller | cisco.ios (11.2.0) was installed successfully
2026-01-22 00:37:37.090910 | controller | Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2026-01-22 00:37:37.090928 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4077anzmy4h0/tmpic6whk_b
2026-01-22 00:37:39.747791 | controller | cisco.iosxr (12.1.1) was installed successfully
2026-01-22 00:37:39.747922 | controller | Installing 'cisco.nxos:11.1.2' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2026-01-22 00:37:39.748161 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.2.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4077anzmy4h0/tmpic6whk_b
2026-01-22 00:37:43.801721 | controller | cisco.nxos (11.1.2) was installed successfully
2026-01-22 00:37:43.801958 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2026-01-22 00:37:43.802581 | 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-4077anzmy4h0/tmpic6whk_b
2026-01-22 00:37:46.208835 | controller | junipernetworks.junos (11.0.0) was installed successfully
2026-01-22 00:37:46.856952 | controller | ok: Runtime: 0:00:31.232376
2026-01-22 00:37:46.883711 | 
2026-01-22 00:37:46.883938 | TASK [podman may need iptables kernel modules]
2026-01-22 00:37:48.259365 | controller | ok: Runtime: 0:00:00.007893
2026-01-22 00:37:48.299625 | 
2026-01-22 00:37:48.299763 | PLAY RECAP
2026-01-22 00:37:48.299821 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2026-01-22 00:37:48.299850 | 
2026-01-22 00:37:48.435970 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-22 00:37:48.437814 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-01-22 00:37:49.084635 | 
2026-01-22 00:37:49.084774 | PLAY [controller]
2026-01-22 00:37:49.107015 | 
2026-01-22 00:37:49.107115 | TASK [Copy the galaxy.yml on the controller]
2026-01-22 00:37:52.882852 | controller | changed
2026-01-22 00:37:52.889882 | 
2026-01-22 00:37:52.889983 | TASK [Load information from galaxy.yml]
2026-01-22 00:37:52.928095 | controller | ok
2026-01-22 00:37:52.936224 | 
2026-01-22 00:37:52.936320 | LOOP [Enable FIPS mode]
2026-01-22 00:37:52.963462 | 
2026-01-22 00:37:52.963711 | LOOP [ansible-test : Include branch specific variables.]
2026-01-22 00:37:53.006504 | controller | ok: "/var/lib/zuul/builds/37e731cd1d6c4b7daccf126d7ec9b752/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2026-01-22 00:37:53.006750 | 
2026-01-22 00:37:53.019893 | 
2026-01-22 00:37:53.020207 | TASK [ansible-test : Setup --skip-tags for test_options]
2026-01-22 00:37:53.066642 | controller | ok
2026-01-22 00:37:53.073008 | 
2026-01-22 00:37:53.073096 | TASK [ansible-test : Enable --inventory for network-integration]
2026-01-22 00:37:53.098564 | controller | skipping: Conditional result was False
2026-01-22 00:37:53.115649 | 
2026-01-22 00:37:53.115916 | TASK [ansible-test : Enable --retry-on-error]
2026-01-22 00:37:53.145643 | controller | skipping: Conditional result was False
2026-01-22 00:37:53.156089 | 
2026-01-22 00:37:53.156236 | TASK [ansible-test : Enable --continue-on-error]
2026-01-22 00:37:53.188504 | controller | ok
2026-01-22 00:37:53.194617 | 
2026-01-22 00:37:53.194705 | TASK [ansible-test : Enable --requirements]
2026-01-22 00:37:53.220557 | controller | skipping: Conditional result was False
2026-01-22 00:37:53.239044 | 
2026-01-22 00:37:53.239268 | TASK [ansible-test : Adjust options for unit tests]
2026-01-22 00:37:53.266896 | controller | skipping: Conditional result was False
2026-01-22 00:37:53.276664 | 
2026-01-22 00:37:53.276798 | TASK [ansible-test : Install requirements with unit tests]
2026-01-22 00:37:53.349891 | controller | ok
2026-01-22 00:37:53.357973 | 
2026-01-22 00:37:53.358137 | TASK [ansible-test : Set --skip-test]
2026-01-22 00:37:53.413070 | controller | skipping: Conditional result was False
2026-01-22 00:37:53.420073 | 
2026-01-22 00:37:53.420156 | TASK [ansible-test : Reduce verbosity]
2026-01-22 00:37:53.463096 | controller | ok
2026-01-22 00:37:53.470986 | 
2026-01-22 00:37:53.471128 | TASK [ansible-test : Setup --python option]
2026-01-22 00:37:53.497204 | controller | skipping: Conditional result was False
2026-01-22 00:37:53.506958 | 
2026-01-22 00:37:53.507073 | TASK [ansible-test : Setup --docker option]
2026-01-22 00:37:53.539189 | controller | ok
2026-01-22 00:37:53.548226 | 
2026-01-22 00:37:53.548409 | TASK [ansible-test : check if MANIFEST.json exists]
2026-01-22 00:37:54.928060 | controller | ok
2026-01-22 00:37:54.944309 | 
2026-01-22 00:37:54.944491 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2026-01-22 00:37:54.972832 | controller | skipping: Conditional result was False
2026-01-22 00:37:54.988212 | 
2026-01-22 00:37:54.988401 | TASK [ansible-test : Load information from MANIFEST.json]
2026-01-22 00:37:55.017077 | controller | skipping: Conditional result was False
2026-01-22 00:37:55.038259 | 
2026-01-22 00:37:55.038478 | TASK [ansible-test : Setup location of project for integration tests]
2026-01-22 00:37:55.066575 | controller | skipping: Conditional result was False
2026-01-22 00:37:55.076015 | 
2026-01-22 00:37:55.076155 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2026-01-22 00:37:56.488235 | controller | ok
2026-01-22 00:37:56.494796 | 
2026-01-22 00:37:56.494891 | TASK [ansible-test : Load information from galaxy.yml]
2026-01-22 00:37:56.549232 | controller | ok
2026-01-22 00:37:56.558439 | 
2026-01-22 00:37:56.558559 | TASK [ansible-test : Setup location of project for integration tests]
2026-01-22 00:37:56.605485 | controller | ok
2026-01-22 00:37:56.615731 | 
2026-01-22 00:37:56.616261 | TASK [ansible-test : Setup minimum test requirements]
2026-01-22 00:37:56.648044 | controller | ok
2026-01-22 00:37:56.653966 | 
2026-01-22 00:37:56.654045 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2026-01-22 00:37:57.645339 | controller | ok
2026-01-22 00:37:57.652757 | 
2026-01-22 00:37:57.652851 | TASK [ansible-test : Install python requirements]
2026-01-22 00:37:59.123120 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-01-22 00:37:59.138381 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2026-01-22 00:37:59.174577 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-01-22 00:37:59.181973 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2026-01-22 00:37:59.193085 | 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)
2026-01-22 00:37:59.200353 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2026-01-22 00:37:59.214141 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2026-01-22 00:37:59.270345 | controller | Installing collected packages: pathspec, yamllint
2026-01-22 00:37:59.356407 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2026-01-22 00:38:00.012408 | controller | ok: Runtime: 0:00:00.997006
2026-01-22 00:38:00.021860 | 
2026-01-22 00:38:00.022003 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2026-01-22 00:38:00.859405 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2026-01-22 00:38:01.390405 | controller | ERROR
2026-01-22 00:38:01.390663 | controller | {
2026-01-22 00:38:01.390699 | controller |   "delta": "0:00:00.007327",
2026-01-22 00:38:01.390755 | controller |   "end": "2026-01-22 00:38:00.859623",
2026-01-22 00:38:01.390792 | controller |   "msg": "non-zero return code",
2026-01-22 00:38:01.390818 | controller |   "rc": 1,
2026-01-22 00:38:01.390843 | controller |   "start": "2026-01-22 00:38:00.852296"
2026-01-22 00:38:01.390864 | controller | }
2026-01-22 00:38:01.390893 | controller | ERROR: Ignoring Errors
2026-01-22 00:38:01.399437 | 
2026-01-22 00:38:01.399539 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2026-01-22 00:38:01.763099 | controller | skipping: Conditional result was False
2026-01-22 00:38:01.773431 | 
2026-01-22 00:38:01.773591 | TASK [ansible-test : Enable persistent connection logging]
2026-01-22 00:38:03.102427 | controller | changed: section and option added
2026-01-22 00:38:03.118208 | 
2026-01-22 00:38:03.118425 | TASK [ansible-test : Set persistent ssh_type value]
2026-01-22 00:38:04.401883 | controller | changed: option added
2026-01-22 00:38:04.407901 | 
2026-01-22 00:38:04.407975 | TASK [ansible-test : Set ansible log path]
2026-01-22 00:38:05.240249 | controller | changed: section and option added
2026-01-22 00:38:05.251369 | 
2026-01-22 00:38:05.251505 | TASK [ansible-test : Identify the changed targets]
2026-01-22 00:38:05.625138 | controller | skipping: Conditional result was False
2026-01-22 00:38:05.644962 | 
2026-01-22 00:38:05.645164 | TASK [ansible-test : set_fact]
2026-01-22 00:38:05.715021 | controller | skipping: Conditional result was False
2026-01-22 00:38:05.729772 | 
2026-01-22 00:38:05.729916 | TASK [ansible-test : Set the targets]
2026-01-22 00:38:05.771664 | controller | ok
2026-01-22 00:38:05.825383 | 
2026-01-22 00:38:05.825515 | TASK [ansible-test : set_fact]
2026-01-22 00:38:05.849425 | controller | skipping: Conditional result was False
2026-01-22 00:38:05.857625 | 
2026-01-22 00:38:05.857792 | TASK [ansible-test : List the targets]
2026-01-22 00:38:06.217601 | controller | skipping: Conditional result was False
2026-01-22 00:38:06.233550 | 
2026-01-22 00:38:06.233783 | TASK [ansible-test : set_fact]
2026-01-22 00:38:06.261339 | controller | skipping: Conditional result was False
2026-01-22 00:38:06.271715 | 
2026-01-22 00:38:06.271879 | TASK [ansible-test : set_fact]
2026-01-22 00:38:06.296981 | controller | skipping: Conditional result was False
2026-01-22 00:38:06.305934 | 
2026-01-22 00:38:06.306064 | LOOP [ansible-test : set_fact]
2026-01-22 00:38:06.381159 | 
2026-01-22 00:38:06.381528 | TASK [ansible-test : set_fact]
2026-01-22 00:38:06.410106 | controller | skipping: Conditional result was False
2026-01-22 00:38:06.422399 | 
2026-01-22 00:38:06.422558 | TASK [ansible-test : debug]
2026-01-22 00:38:06.440017 | controller | skipping: Conditional result was False
2026-01-22 00:38:06.451258 | 
2026-01-22 00:38:06.451414 | TASK [ansible-test : debug]
2026-01-22 00:38:06.487391 | About to run: ansible-test sanity --requirements --docker -v
2026-01-22 00:38:06.494146 | 
2026-01-22 00:38:06.494242 | TASK [ansible-test : Run the test suite]
2026-01-22 00:38:07.913241 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2026-01-22 00:38:07.984722 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2026-01-22 00:38:09.585997 | controller | 2.9.0: Pulling from ansible/default-test-container
2026-01-22 00:38:09.586059 | controller | 171857c49d0f: Pulling fs layer
2026-01-22 00:38:09.586083 | controller | 419640447d26: Pulling fs layer
2026-01-22 00:38:09.586090 | controller | 61e52f862619: Pulling fs layer
2026-01-22 00:38:09.586097 | controller | 851de0426e26: Pulling fs layer
2026-01-22 00:38:09.586107 | controller | 37425716ea5b: Pulling fs layer
2026-01-22 00:38:09.586117 | controller | cd9b0c439616: Pulling fs layer
2026-01-22 00:38:09.586123 | controller | d4612dbd8855: Pulling fs layer
2026-01-22 00:38:09.586129 | controller | 554699364730: Pulling fs layer
2026-01-22 00:38:09.586135 | controller | 1ff15d4bdb3e: Pulling fs layer
2026-01-22 00:38:09.586148 | controller | 5f2e4724adea: Pulling fs layer
2026-01-22 00:38:09.586154 | controller | a02d9a95ccb8: Pulling fs layer
2026-01-22 00:38:09.586160 | controller | 989f4ce370cb: Pulling fs layer
2026-01-22 00:38:09.586167 | controller | 0d7f52cdf9df: Pulling fs layer
2026-01-22 00:38:09.586173 | controller | c423742351d5: Pulling fs layer
2026-01-22 00:38:09.586179 | controller | 109236341d21: Pulling fs layer
2026-01-22 00:38:09.586187 | controller | 07a2f61c96b8: Pulling fs layer
2026-01-22 00:38:09.586193 | controller | 041f122f27ae: Pulling fs layer
2026-01-22 00:38:09.586200 | controller | ca6f5098b12e: Pulling fs layer
2026-01-22 00:38:09.586206 | controller | 1eb3e7b2afd9: Pulling fs layer
2026-01-22 00:38:09.586212 | controller | 18a0b750727a: Pulling fs layer
2026-01-22 00:38:09.586218 | controller | 17f2f596716c: Pulling fs layer
2026-01-22 00:38:09.586226 | controller | f35a9998a9bd: Pulling fs layer
2026-01-22 00:38:09.586232 | controller | fdfc10566405: Pulling fs layer
2026-01-22 00:38:09.586245 | controller | 1ff1c442c5fe: Pulling fs layer
2026-01-22 00:38:09.586251 | controller | a3443c4a17ed: Pulling fs layer
2026-01-22 00:38:09.586257 | controller | 0edcf9fb2b15: Pulling fs layer
2026-01-22 00:38:09.586263 | controller | 5af43b69e845: Pulling fs layer
2026-01-22 00:38:09.586269 | controller | 1651773effb2: Pulling fs layer
2026-01-22 00:38:09.586275 | controller | 79e23c5c0fe4: Pulling fs layer
2026-01-22 00:38:09.586285 | controller | e5058b1faaa6: Pulling fs layer
2026-01-22 00:38:09.586291 | controller | 846775490569: Pulling fs layer
2026-01-22 00:38:09.586297 | controller | 851de0426e26: Waiting
2026-01-22 00:38:09.586303 | controller | 37425716ea5b: Waiting
2026-01-22 00:38:09.586309 | controller | cd9b0c439616: Waiting
2026-01-22 00:38:09.586315 | controller | d4612dbd8855: Waiting
2026-01-22 00:38:09.586328 | controller | 554699364730: Waiting
2026-01-22 00:38:09.586336 | controller | 1ff15d4bdb3e: Waiting
2026-01-22 00:38:09.991641 | controller | 5f2e4724adea: Waiting
2026-01-22 00:38:09.991704 | controller | a02d9a95ccb8: Waiting
2026-01-22 00:38:09.991711 | controller | 989f4ce370cb: Waiting
2026-01-22 00:38:09.991717 | controller | 0d7f52cdf9df: Waiting
2026-01-22 00:38:09.991724 | controller | c423742351d5: Waiting
2026-01-22 00:38:09.991730 | controller | 109236341d21: Waiting
2026-01-22 00:38:09.991736 | controller | 07a2f61c96b8: Waiting
2026-01-22 00:38:09.991741 | controller | 041f122f27ae: Waiting
2026-01-22 00:38:09.991747 | controller | ca6f5098b12e: Waiting
2026-01-22 00:38:09.991753 | controller | 1eb3e7b2afd9: Waiting
2026-01-22 00:38:09.991759 | controller | 18a0b750727a: Waiting
2026-01-22 00:38:09.991765 | controller | 17f2f596716c: Waiting
2026-01-22 00:38:09.991774 | controller | f35a9998a9bd: Waiting
2026-01-22 00:38:09.991779 | controller | fdfc10566405: Waiting
2026-01-22 00:38:09.991785 | controller | 1ff1c442c5fe: Waiting
2026-01-22 00:38:09.991791 | controller | a3443c4a17ed: Waiting
2026-01-22 00:38:09.991797 | controller | 0edcf9fb2b15: Waiting
2026-01-22 00:38:09.991803 | controller | 5af43b69e845: Waiting
2026-01-22 00:38:09.991809 | controller | 1651773effb2: Waiting
2026-01-22 00:38:09.991815 | controller | 79e23c5c0fe4: Waiting
2026-01-22 00:38:09.991821 | controller | e5058b1faaa6: Waiting
2026-01-22 00:38:09.991828 | controller | 846775490569: Waiting
2026-01-22 00:38:09.991843 | controller | 419640447d26: Verifying Checksum
2026-01-22 00:38:10.045291 | controller | 419640447d26: Download complete
2026-01-22 00:38:10.045350 | controller | 61e52f862619: Verifying Checksum
2026-01-22 00:38:10.045448 | controller | 61e52f862619: Download complete
2026-01-22 00:38:10.306391 | controller | 171857c49d0f: Verifying Checksum
2026-01-22 00:38:10.801727 | controller | 171857c49d0f: Download complete
2026-01-22 00:38:10.801782 | controller | 851de0426e26: Verifying Checksum
2026-01-22 00:38:11.291326 | controller | 851de0426e26: Download complete
2026-01-22 00:38:11.291385 | controller | cd9b0c439616: Verifying Checksum
2026-01-22 00:38:11.622293 | controller | cd9b0c439616: Download complete
2026-01-22 00:38:11.622346 | controller | d4612dbd8855: Verifying Checksum
2026-01-22 00:38:11.622485 | controller | d4612dbd8855: Download complete
2026-01-22 00:38:12.461433 | controller | 1ff15d4bdb3e: Verifying Checksum
2026-01-22 00:38:13.416136 | controller | 1ff15d4bdb3e: Download complete
2026-01-22 00:38:13.416189 | controller | 171857c49d0f: Pull complete
2026-01-22 00:38:13.449887 | controller | 419640447d26: Pull complete
2026-01-22 00:38:13.474424 | controller | 61e52f862619: Pull complete
2026-01-22 00:38:13.510576 | controller | 851de0426e26: Pull complete
2026-01-22 00:38:13.522049 | controller | 5f2e4724adea: Verifying Checksum
2026-01-22 00:38:14.907561 | controller | 5f2e4724adea: Download complete
2026-01-22 00:38:14.907620 | controller | a02d9a95ccb8: Verifying Checksum
2026-01-22 00:38:15.618933 | controller | 989f4ce370cb: Verifying Checksum
2026-01-22 00:38:16.352861 | controller | 989f4ce370cb: Download complete
2026-01-22 00:38:16.352947 | controller | 0d7f52cdf9df: Verifying Checksum
2026-01-22 00:38:17.036481 | controller | 0d7f52cdf9df: Download complete
2026-01-22 00:38:17.036550 | controller | c423742351d5: Verifying Checksum
2026-01-22 00:38:26.798528 | controller | c423742351d5: Download complete
2026-01-22 00:38:26.798618 | controller | 109236341d21: Verifying Checksum
2026-01-22 00:38:26.798687 | controller | 109236341d21: Download complete
2026-01-22 00:38:26.944010 | controller | 554699364730: Verifying Checksum
2026-01-22 00:38:26.944096 | controller | 554699364730: Download complete
2026-01-22 00:38:27.533952 | controller | 07a2f61c96b8: Verifying Checksum
2026-01-22 00:38:27.534096 | controller | 07a2f61c96b8: Download complete
2026-01-22 00:38:27.626279 | controller | 041f122f27ae: Verifying Checksum
2026-01-22 00:38:27.626512 | controller | 041f122f27ae: Download complete
2026-01-22 00:38:28.906547 | controller | 1eb3e7b2afd9: Verifying Checksum
2026-01-22 00:38:28.907156 | controller | 1eb3e7b2afd9: Download complete
2026-01-22 00:38:29.160212 | controller | ca6f5098b12e: Verifying Checksum
2026-01-22 00:38:29.160307 | controller | ca6f5098b12e: Download complete
2026-01-22 00:38:30.360245 | controller | 18a0b750727a: Verifying Checksum
2026-01-22 00:38:30.360291 | controller | 18a0b750727a: Download complete
2026-01-22 00:38:30.517420 | controller | 17f2f596716c: Download complete
2026-01-22 00:38:31.078703 | controller | f35a9998a9bd: Verifying Checksum
2026-01-22 00:38:31.078784 | controller | f35a9998a9bd: Download complete
2026-01-22 00:38:31.267474 | controller | fdfc10566405: Verifying Checksum
2026-01-22 00:38:31.267552 | controller | fdfc10566405: Download complete
2026-01-22 00:38:32.129813 | controller | 1ff1c442c5fe: Verifying Checksum
2026-01-22 00:38:32.129891 | controller | 1ff1c442c5fe: Download complete
2026-01-22 00:38:37.956920 | controller | a3443c4a17ed: Verifying Checksum
2026-01-22 00:38:37.957034 | controller | a3443c4a17ed: Download complete
2026-01-22 00:38:38.863772 | controller | 0edcf9fb2b15: Verifying Checksum
2026-01-22 00:38:38.863845 | controller | 0edcf9fb2b15: Download complete
2026-01-22 00:38:44.216281 | controller | 5af43b69e845: Verifying Checksum
2026-01-22 00:38:44.216494 | controller | 5af43b69e845: Download complete
2026-01-22 00:38:44.455816 | controller | 1651773effb2: Verifying Checksum
2026-01-22 00:38:44.455979 | controller | 1651773effb2: Download complete
2026-01-22 00:38:46.041158 | controller | 37425716ea5b: Verifying Checksum
2026-01-22 00:38:46.041249 | controller | 37425716ea5b: Download complete
2026-01-22 00:38:49.070706 | controller | 79e23c5c0fe4: Verifying Checksum
2026-01-22 00:38:49.070824 | controller | 79e23c5c0fe4: Download complete
2026-01-22 00:38:49.070895 | controller | e5058b1faaa6: Verifying Checksum
2026-01-22 00:38:49.070962 | controller | e5058b1faaa6: Download complete
2026-01-22 00:38:53.270766 | controller | 846775490569: Verifying Checksum
2026-01-22 00:38:53.270904 | controller | 846775490569: Download complete
2026-01-22 00:39:05.937213 | controller | 37425716ea5b: Pull complete
2026-01-22 00:39:05.966986 | controller | cd9b0c439616: Pull complete
2026-01-22 00:39:05.989964 | controller | d4612dbd8855: Pull complete
2026-01-22 00:39:10.448626 | controller | 554699364730: Pull complete
2026-01-22 00:39:10.477198 | controller | 1ff15d4bdb3e: Pull complete
2026-01-22 00:39:10.502462 | controller | 5f2e4724adea: Pull complete
2026-01-22 00:39:10.552574 | controller | a02d9a95ccb8: Pull complete
2026-01-22 00:39:10.578412 | controller | 989f4ce370cb: Pull complete
2026-01-22 00:39:10.601928 | controller | 0d7f52cdf9df: Pull complete
2026-01-22 00:39:10.637753 | controller | c423742351d5: Pull complete
2026-01-22 00:39:12.908338 | controller | 109236341d21: Pull complete
2026-01-22 00:39:12.943736 | controller | 07a2f61c96b8: Pull complete
2026-01-22 00:39:12.980498 | controller | 041f122f27ae: Pull complete
2026-01-22 00:39:13.173000 | controller | ca6f5098b12e: Pull complete
2026-01-22 00:39:13.219860 | controller | 1eb3e7b2afd9: Pull complete
2026-01-22 00:39:13.264288 | controller | 18a0b750727a: Pull complete
2026-01-22 00:39:13.289149 | controller | 17f2f596716c: Pull complete
2026-01-22 00:39:13.326274 | controller | f35a9998a9bd: Pull complete
2026-01-22 00:39:13.368844 | controller | fdfc10566405: Pull complete
2026-01-22 00:39:13.398292 | controller | 1ff1c442c5fe: Pull complete
2026-01-22 00:39:15.025486 | controller | a3443c4a17ed: Pull complete
2026-01-22 00:39:16.797459 | controller | 0edcf9fb2b15: Pull complete
2026-01-22 00:39:19.963130 | controller | 5af43b69e845: Pull complete
2026-01-22 00:39:23.836728 | controller | 1651773effb2: Pull complete
2026-01-22 00:39:26.399178 | controller | 79e23c5c0fe4: Pull complete
2026-01-22 00:39:28.315861 | controller | e5058b1faaa6: Pull complete
2026-01-22 00:39:30.975013 | controller | 846775490569: Pull complete
2026-01-22 00:39:31.000045 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2026-01-22 00:39:31.006318 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2026-01-22 00:39:31.016365 | controller | quay.io/ansible/default-test-container:2.9.0
2026-01-22 00:39:31.018907 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2026-01-22 00:39:31.046634 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2026-01-22 00:39:32.556890 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2026-01-22 00:39:32.557102 | controller | 04a5f4cda3ee: Pulling fs layer
2026-01-22 00:39:32.557150 | controller | ff496a88c8ed: Pulling fs layer
2026-01-22 00:39:32.557184 | controller | 0ce83f459fe7: Pulling fs layer
2026-01-22 00:39:32.557216 | controller | 2e5170e1f099: Pulling fs layer
2026-01-22 00:39:32.557362 | controller | 7641eb41b08c: Pulling fs layer
2026-01-22 00:39:32.557398 | controller | ad15fa9da398: Pulling fs layer
2026-01-22 00:39:32.557431 | controller | 087d91352424: Pulling fs layer
2026-01-22 00:39:32.557463 | controller | 8b92efd6a100: Pulling fs layer
2026-01-22 00:39:32.557497 | controller | 2e5170e1f099: Waiting
2026-01-22 00:39:32.557529 | controller | 7641eb41b08c: Waiting
2026-01-22 00:39:32.557569 | controller | ad15fa9da398: Waiting
2026-01-22 00:39:32.557603 | controller | 087d91352424: Waiting
2026-01-22 00:39:32.557651 | controller | 8b92efd6a100: Waiting
2026-01-22 00:39:33.245562 | controller | 0ce83f459fe7: Verifying Checksum
2026-01-22 00:39:33.245969 | controller | 0ce83f459fe7: Download complete
2026-01-22 00:39:33.326924 | controller | ff496a88c8ed: Verifying Checksum
2026-01-22 00:39:34.022826 | controller | ff496a88c8ed: Download complete
2026-01-22 00:39:34.022901 | controller | 7641eb41b08c: Verifying Checksum
2026-01-22 00:39:34.023113 | controller | 7641eb41b08c: Download complete
2026-01-22 00:39:34.744061 | controller | ad15fa9da398: Verifying Checksum
2026-01-22 00:39:36.188048 | controller | ad15fa9da398: Download complete
2026-01-22 00:39:36.188118 | controller | 087d91352424: Verifying Checksum
2026-01-22 00:39:36.188188 | controller | 087d91352424: Download complete
2026-01-22 00:39:36.963975 | controller | 8b92efd6a100: Verifying Checksum
2026-01-22 00:39:38.006363 | controller | 8b92efd6a100: Download complete
2026-01-22 00:39:38.006466 | controller | 04a5f4cda3ee: Verifying Checksum
2026-01-22 00:39:38.006553 | controller | 04a5f4cda3ee: Download complete
2026-01-22 00:39:38.764848 | controller | 2e5170e1f099: Verifying Checksum
2026-01-22 00:39:38.764962 | controller | 2e5170e1f099: Download complete
2026-01-22 00:39:39.924007 | controller | 04a5f4cda3ee: Pull complete
2026-01-22 00:39:39.961452 | controller | ff496a88c8ed: Pull complete
2026-01-22 00:39:40.078685 | controller | 0ce83f459fe7: Pull complete
2026-01-22 00:39:41.720704 | controller | 2e5170e1f099: Pull complete
2026-01-22 00:39:41.754011 | controller | 7641eb41b08c: Pull complete
2026-01-22 00:39:41.789623 | controller | ad15fa9da398: Pull complete
2026-01-22 00:39:42.750475 | controller | 087d91352424: Pull complete
2026-01-22 00:39:42.777408 | controller | 8b92efd6a100: Pull complete
2026-01-22 00:39:42.795681 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2026-01-22 00:39:42.800223 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2026-01-22 00:39:42.809656 | controller | quay.io/ansible/pypi-test-container:1.0.0
2026-01-22 00:39:42.815622 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2026-01-22 00:39:52.069416 | controller | Run command: docker inspect d85419d34e03195f173817f834a6002a3921c1fadaf91cc18ccb779e45d5744d
2026-01-22 00:39:52.145918 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2026-01-22 00:39:52.162191 | controller | Including collection: ansible.netcommon (287 files)
2026-01-22 00:39:52.173518 | controller | Including collection: ansible.network (23 files)
2026-01-22 00:39:52.173566 | controller | Including collection: ansible.utils (441 files)
2026-01-22 00:39:52.218097 | controller | Including collection: arista.eos (1022 files)
2026-01-22 00:39:52.265644 | controller | Including collection: cisco.ios (1174 files)
2026-01-22 00:39:52.311747 | controller | Including collection: cisco.iosxr (1044 files)
2026-01-22 00:39:52.385600 | controller | Including collection: cisco.nxos (1687 files)
2026-01-22 00:39:52.429060 | controller | Including collection: junipernetworks.junos (963 files)
2026-01-22 00:39:52.471863 | controller | Creating a payload archive containing 7367 files...
2026-01-22 00:39:54.614540 | controller | Created a 6007727 byte payload archive containing 7367 files in 2 seconds.
2026-01-22 00:39:54.618572 | controller | Assuming Docker is available on localhost.
2026-01-22 00:39:54.619039 | 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
2026-01-22 00:39:55.150379 | controller | Run command: docker exec -i 58283f5416f089d1a1207661ee4b439b1280d003d539bc1aae5a476f17942a7e dd of=/root/docker.sh bs=65536
2026-01-22 00:39:55.229007 | controller | Run command: docker exec 58283f5416f089d1a1207661ee4b439b1280d003d539bc1aae5a476f17942a7e /bin/bash /root/docker.sh
2026-01-22 00:39:55.296709 | controller | Run command: docker exec -i 58283f5416f089d1a1207661ee4b439b1280d003d539bc1aae5a476f17942a7e dd of=/root/test.tgz bs=65536
2026-01-22 00:39:55.422958 | controller | Run command: docker exec 58283f5416f089d1a1207661ee4b439b1280d003d539bc1aae5a476f17942a7e tar oxzf /root/test.tgz -C /root
2026-01-22 00:39:56.465009 | controller | Run command: docker exec 58283f5416f089d1a1207661ee4b439b1280d003d539bc1aae5a476f17942a7e /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-kgujyrwq.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2026-01-22 00:39:56.889531 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2026-01-22 00:39:56.890044 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2026-01-22 00:39:56.890507 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2026-01-22 00:39:56.892658 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2026-01-22 00:39:56.893502 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2026-01-22 00:39:56.902742 | controller | No tests applicable.
2026-01-22 00:39:56.903048 | controller | Running sanity test 'ansible-doc' with Python 3.6
2026-01-22 00:39:56.903067 | controller | No tests applicable.
2026-01-22 00:39:56.903706 | controller | Running sanity test 'changelog' with Python 3.6
2026-01-22 00:39:56.903729 | 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
2026-01-22 00:39:57.896148 | 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
2026-01-22 00:39:58.496083 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2026-01-22 00:40:13.516255 | 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/
2026-01-22 00:40:29.033916 | 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/
2026-01-22 00:40:45.051385 | 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/
2026-01-22 00:41:02.071059 | 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/
2026-01-22 00:41:21.091195 | 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/
2026-01-22 00:41:36.100978 | 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)
2026-01-22 00:41:36.101716 | 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))
2026-01-22 00:41:36.213092 | 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.
2026-01-22 00:41:36.213291 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2026-01-22 00:41:36.213388 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2026-01-22 00:41:36.255798 | controller | Removing custom PyPI hosts entries: /etc/hosts
2026-01-22 00:41:36.255858 | controller | Run command: docker exec 58283f5416f089d1a1207661ee4b439b1280d003d539bc1aae5a476f17942a7e tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2026-01-22 00:41:36.337279 | controller | Run command: docker exec -i 58283f5416f089d1a1207661ee4b439b1280d003d539bc1aae5a476f17942a7e dd if=/root/output.tgz bs=65536
2026-01-22 00:41:36.418630 | controller | Run command: tar oxzf /tmp/ansible-result-a1upf324.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2026-01-22 00:41:36.433953 | controller | Run command: docker rm -f d85419d34e03195f173817f834a6002a3921c1fadaf91cc18ccb779e45d5744d
2026-01-22 00:41:36.666051 | controller | Run command: docker rm -f 58283f5416f089d1a1207661ee4b439b1280d003d539bc1aae5a476f17942a7e
2026-01-22 00:41:37.382413 | controller | ERROR: Command "docker exec 58283f5416f089d1a1207661ee4b439b1280d003d539bc1aae5a476f17942a7e /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-kgujyrwq.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2026-01-22 00:41:37.996648 | controller | ERROR
2026-01-22 00:41:37.997054 | controller | {
2026-01-22 00:41:37.997098 | controller |   "delta": "0:03:29.793825",
2026-01-22 00:41:37.997125 | controller |   "end": "2026-01-22 00:41:37.437360",
2026-01-22 00:41:37.997149 | controller |   "msg": "non-zero return code",
2026-01-22 00:41:37.997170 | controller |   "rc": 1,
2026-01-22 00:41:37.997192 | controller |   "start": "2026-01-22 00:38:07.643535"
2026-01-22 00:41:37.997233 | controller | }
failure
2026-01-22 00:41:38.004674 | 
2026-01-22 00:41:38.004792 | PLAY RECAP
2026-01-22 00:41:38.004860 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2026-01-22 00:41:38.004889 | 
2026-01-22 00:41:38.161831 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-01-22 00:41:38.163657 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-22 00:41:38.965632 | 
2026-01-22 00:41:38.965785 | PLAY [all]
2026-01-22 00:41:38.989464 | 
2026-01-22 00:41:38.989602 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-01-22 00:41:41.993934 | controller | changed: non-zero return code
2026-01-22 00:41:42.003373 | 
2026-01-22 00:41:42.003502 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-01-22 00:41:42.030156 | controller | skipping: Conditional result was False
2026-01-22 00:41:42.046650 | 
2026-01-22 00:41:42.046933 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-01-22 00:41:42.075372 | 
2026-01-22 00:41:42.075578 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-01-22 00:41:42.111785 | 
2026-01-22 00:41:42.111993 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-01-22 00:41:42.127801 | controller | skipping: Conditional result was False
2026-01-22 00:41:42.137662 | 
2026-01-22 00:41:42.137812 | LOOP [fetch-subunit-output : Generate subunit file]
2026-01-22 00:41:42.172570 | 
2026-01-22 00:41:42.172813 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-01-22 00:41:42.199053 | controller | skipping: Conditional result was False
2026-01-22 00:41:42.210973 | 
2026-01-22 00:41:42.211116 | TASK [fetch-subunit-output : Remove the temporary file]
2026-01-22 00:41:42.226672 | controller | skipping: Conditional result was False
2026-01-22 00:41:42.233430 | 
2026-01-22 00:41:42.233515 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-01-22 00:41:42.248226 | controller | skipping: Conditional result was False
2026-01-22 00:41:42.277987 | 
2026-01-22 00:41:42.278092 | PLAY RECAP
2026-01-22 00:41:42.278135 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-22 00:41:42.278157 | 
2026-01-22 00:41:42.378669 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-22 00:41:42.379922 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-01-22 00:41:43.012772 | 
2026-01-22 00:41:43.012897 | PLAY [all:!appliance*]
2026-01-22 00:41:43.037021 | 
2026-01-22 00:41:43.037168 | TASK [unregister the node]
2026-01-22 00:41:43.393495 | controller | skipping: Conditional result was False
2026-01-22 00:41:43.402514 | 
2026-01-22 00:41:43.402635 | TASK [include_role : fetch-output]
2026-01-22 00:41:43.444905 | controller | ok
2026-01-22 00:41:43.466371 | 
2026-01-22 00:41:43.466477 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-22 00:41:43.532054 | controller | skipping: Conditional result was False
2026-01-22 00:41:43.542478 | 
2026-01-22 00:41:43.542635 | TASK [fetch-output : Set log path for single node]
2026-01-22 00:41:43.584273 | controller | ok
2026-01-22 00:41:43.590049 | 
2026-01-22 00:41:43.590153 | LOOP [fetch-output : Ensure local output dirs]
2026-01-22 00:41:44.006161 | controller -> localhost | ok: "/var/lib/zuul/builds/37e731cd1d6c4b7daccf126d7ec9b752/work/logs"
2026-01-22 00:41:44.280549 | controller -> localhost | changed: "/var/lib/zuul/builds/37e731cd1d6c4b7daccf126d7ec9b752/work/artifacts"
2026-01-22 00:41:44.520943 | controller -> localhost | changed: "/var/lib/zuul/builds/37e731cd1d6c4b7daccf126d7ec9b752/work/docs"
2026-01-22 00:41:44.554711 | 
2026-01-22 00:41:44.554895 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-22 00:41:46.810576 | controller | changed:
2026-01-22 00:41:46.810819 | controller | .d..t...... ./
2026-01-22 00:41:46.810860 | controller | cd+++++++++ controller/
2026-01-22 00:41:46.810904 | controller | changed: All items complete
2026-01-22 00:41:46.810930 | 
2026-01-22 00:41:48.855461 | controller | changed: .d..t...... ./
2026-01-22 00:41:50.975315 | controller | changed: .d..t...... ./
2026-01-22 00:41:51.000674 | 
2026-01-22 00:41:51.000811 | TASK [include_role : fetch-output-openshift]
2026-01-22 00:41:51.015707 | controller | skipping: Conditional result was False
2026-01-22 00:41:51.024565 | 
2026-01-22 00:41:51.024744 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-22 00:41:51.053912 | controller | skipping: Conditional result was False
2026-01-22 00:41:51.063006 | controller | skipping: Conditional result was False
2026-01-22 00:41:51.099632 | 
2026-01-22 00:41:51.099758 | PLAY [localhost]
2026-01-22 00:41:51.113417 | 
2026-01-22 00:41:51.113522 | TASK [Run Zuul manifest role]
2026-01-22 00:41:51.132432 | localhost | ok
2026-01-22 00:41:51.151753 | 
2026-01-22 00:41:51.151924 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-22 00:41:51.519023 | localhost | changed
2026-01-22 00:41:51.523800 | 
2026-01-22 00:41:51.523863 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-22 00:41:51.553761 | localhost | ok
2026-01-22 00:41:51.561970 | 
2026-01-22 00:41:51.562035 | TASK [Set zuul-log-path fact]
2026-01-22 00:41:51.626813 | localhost | ok
2026-01-22 00:41:51.642377 | 
2026-01-22 00:41:51.642514 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-22 00:41:51.673785 | localhost | ok
2026-01-22 00:41:51.682544 | 
2026-01-22 00:41:51.682618 | LOOP [Run upload-logs-swift role]
2026-01-22 00:41:51.719760 | localhost | Output suppressed because no_log was given
2026-01-22 00:41:51.748698 | 
2026-01-22 00:41:51.748857 | TASK [Set zuul-log-path fact]
2026-01-22 00:41:51.775061 | localhost | skipping: Conditional result was False
2026-01-22 00:41:51.782779 | 
2026-01-22 00:41:51.782915 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-01-22 00:41:52.173956 | localhost -> localhost | ok: Runtime: 0:00:00.007074
2026-01-22 00:41:52.180141 | 
2026-01-22 00:41:52.180211 | TASK [upload-logs-swift : Upload logs to swift]