2025-11-12 00:30:02.129388 | Job console starting...
2025-11-12 00:30:02.141279 | Updating repositories
2025-11-12 00:30:02.332501 | Preparing job workspace
2025-11-12 00:30:06.300593 | Running Ansible setup...
2025-11-12 00:30:14.150968 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-12 00:30:14.835585 | 
2025-11-12 00:30:14.835758 | PLAY [localhost]
2025-11-12 00:30:14.845016 | 
2025-11-12 00:30:14.845134 | TASK [Gathering Facts]
2025-11-12 00:30:15.877055 | localhost | ok
2025-11-12 00:30:15.913751 | 
2025-11-12 00:30:15.913924 | TASK [Setup log path fact]
2025-11-12 00:30:15.935837 | localhost | ok
2025-11-12 00:30:15.953339 | 
2025-11-12 00:30:15.953459 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-12 00:30:15.984538 | localhost | ok
2025-11-12 00:30:15.995319 | 
2025-11-12 00:30:15.995455 | TASK [emit-job-header : Print job information]
2025-11-12 00:30:16.050322 | # Job Information
2025-11-12 00:30:16.050577 | Ansible Version: 2.15.12
2025-11-12 00:30:16.050644 | Job: ansible-test-sanity-docker-stable-2.10
2025-11-12 00:30:16.051020 | Pipeline: periodic
2025-11-12 00:30:16.051094 | Executor: ze02.softwarefactory-project.io
2025-11-12 00:30:16.051140 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-11-12 00:30:16.051188 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/134/ansible/1343c62253824f3ba2006942f45f7805/
2025-11-12 00:30:16.051230 | Event ID: edca0ab7305f4724aa2c2c639624c9e2
2025-11-12 00:30:16.059273 | 
2025-11-12 00:30:16.059404 | LOOP [emit-job-header : Print node information]
2025-11-12 00:30:16.176465 | localhost | ok:
2025-11-12 00:30:16.176638 | localhost | # Node Information
2025-11-12 00:30:16.176711 | localhost | Inventory Hostname: controller
2025-11-12 00:30:16.176741 | localhost | Hostname: np0005518893
2025-11-12 00:30:16.176768 | localhost | Username: zuul
2025-11-12 00:30:16.176798 | localhost | Distro: Fedora 37
2025-11-12 00:30:16.176822 | localhost | Provider: ansible-vexxhost-ams1
2025-11-12 00:30:16.176846 | localhost | Region: ams1
2025-11-12 00:30:16.176868 | localhost | Label: ansible-fedora-37-1vcpu
2025-11-12 00:30:16.176891 | localhost | Product Name: OpenStack Nova
2025-11-12 00:30:16.176931 | localhost | Interface IP: 38.129.16.225
2025-11-12 00:30:16.187473 | 
2025-11-12 00:30:16.187561 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-12 00:30:16.624251 | localhost -> localhost | changed
2025-11-12 00:30:16.636248 | 
2025-11-12 00:30:16.636409 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-12 00:30:17.594247 | localhost -> localhost | changed
2025-11-12 00:30:17.622695 | 
2025-11-12 00:30:17.622809 | PLAY [all:!appliance*]
2025-11-12 00:30:17.646208 | 
2025-11-12 00:30:17.646316 | TASK [include_role : start-zuul-console]
2025-11-12 00:30:17.668970 | controller | ok
2025-11-12 00:30:17.688090 | 
2025-11-12 00:30:17.688158 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-12 00:30:18.781385 | controller | ok
2025-11-12 00:30:18.806423 | 
2025-11-12 00:30:18.806572 | TASK [use-our-mirror : Retrieve the IP address]
2025-11-12 00:30:21.198496 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-11-12 00:30:21.205096 | 
2025-11-12 00:30:21.205174 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-11-12 00:30:21.563342 | controller | skipping: Conditional result was False
2025-11-12 00:30:21.570043 | 
2025-11-12 00:30:21.570135 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-11-12 00:30:21.595000 | controller | skipping: Conditional result was False
2025-11-12 00:30:21.611689 | 
2025-11-12 00:30:21.612070 | TASK [use-our-mirror : Create the podman configuration directory]
2025-11-12 00:30:21.639392 | controller | skipping: Conditional result was False
2025-11-12 00:30:21.656795 | 
2025-11-12 00:30:21.657010 | TASK [use-our-mirror : Copy the podman configuration]
2025-11-12 00:30:21.685018 | controller | skipping: Conditional result was False
2025-11-12 00:30:21.701007 | 
2025-11-12 00:30:21.701218 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-11-12 00:30:21.729184 | controller | skipping: Conditional result was False
2025-11-12 00:30:21.738478 | 
2025-11-12 00:30:21.738588 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-11-12 00:30:21.763543 | controller | skipping: Conditional result was False
2025-11-12 00:30:21.780016 | 
2025-11-12 00:30:21.780182 | TASK [Disable Fedora Modular]
2025-11-12 00:30:22.993721 | controller | changed
2025-11-12 00:30:23.006409 | 
2025-11-12 00:30:23.006575 | TASK [Enable EPEL]
2025-11-12 00:30:23.034004 | controller | skipping: Conditional result was False
2025-11-12 00:30:23.048066 | 
2025-11-12 00:30:23.048233 | TASK [Register the RHEL node]
2025-11-12 00:30:23.475870 | 
2025-11-12 00:30:23.476165 | TASK [Show the subscription-manager status]
2025-11-12 00:30:23.871645 | controller | skipping: Conditional result was False
2025-11-12 00:30:23.879702 | 
2025-11-12 00:30:23.879788 | TASK [Enable EPEL on RHEL]
2025-11-12 00:30:24.267283 | controller | skipping: Conditional result was False
2025-11-12 00:30:24.280579 | 
2025-11-12 00:30:24.280759 | TASK [Install git and tox]
2025-11-12 00:32:52.984293 | controller | changed
2025-11-12 00:32:52.993533 | 
2025-11-12 00:32:52.993731 | TASK [include_role : prepare-workspace]
2025-11-12 00:32:53.029762 | controller | ok
2025-11-12 00:32:53.064380 | 
2025-11-12 00:32:53.064563 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-12 00:32:53.924971 | controller | ok
2025-11-12 00:32:53.934790 | 
2025-11-12 00:32:53.934919 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-12 00:33:09.616639 | controller | Output suppressed because no_log was given
2025-11-12 00:33:09.645761 | 
2025-11-12 00:33:09.646011 | TASK [include_role : prepare-workspace-openshift]
2025-11-12 00:33:09.675360 | controller | skipping: Conditional result was False
2025-11-12 00:33:09.770571 | 
2025-11-12 00:33:09.770736 | PLAY [all:!appliance]
2025-11-12 00:33:09.787754 | 
2025-11-12 00:33:09.787922 | TASK [Run add-build-sshkey role (RSA)]
2025-11-12 00:33:09.822683 | controller | ok
2025-11-12 00:33:09.845029 | 
2025-11-12 00:33:09.845177 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-12 00:33:10.077403 | controller -> localhost | ok
2025-11-12 00:33:10.085530 | 
2025-11-12 00:33:10.085624 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-12 00:33:10.123141 | controller | ok
2025-11-12 00:33:10.138458 | controller | included: /var/lib/zuul/builds/1343c62253824f3ba2006942f45f7805/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-12 00:33:10.145119 | 
2025-11-12 00:33:10.145200 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-12 00:33:10.676061 | controller -> localhost | Generating public/private rsa key pair.
2025-11-12 00:33:10.676356 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/1343c62253824f3ba2006942f45f7805/work/1343c62253824f3ba2006942f45f7805_id_rsa.
2025-11-12 00:33:10.676387 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/1343c62253824f3ba2006942f45f7805/work/1343c62253824f3ba2006942f45f7805_id_rsa.pub.
2025-11-12 00:33:10.676408 | controller -> localhost | The key fingerprint is:
2025-11-12 00:33:10.676429 | controller -> localhost | SHA256:AqtHXadb0T2/OWU9Gum2QEKHEUxLbxSL77av2nKMqw0 zuul-build-sshkey
2025-11-12 00:33:10.676449 | controller -> localhost | The key's randomart image is:
2025-11-12 00:33:10.676468 | controller -> localhost | +---[RSA 2048]----+
2025-11-12 00:33:10.676486 | controller -> localhost | |        o=.o.    |
2025-11-12 00:33:10.676506 | controller -> localhost | |        ..B...   |
2025-11-12 00:33:10.676525 | controller -> localhost | |    .   .=o=. o  |
2025-11-12 00:33:10.676542 | controller -> localhost | |     + ..o+.  .o.|
2025-11-12 00:33:10.676560 | controller -> localhost | |    o o S..o o .=|
2025-11-12 00:33:10.676592 | controller -> localhost | |   o   . o+ . o.=|
2025-11-12 00:33:10.676620 | controller -> localhost | |  . .  E. o+ + + |
2025-11-12 00:33:10.676643 | controller -> localhost | |   .    oooo+ . .|
2025-11-12 00:33:10.676667 | controller -> localhost | |       ..+=+oo   |
2025-11-12 00:33:10.676720 | controller -> localhost | +----[SHA256]-----+
2025-11-12 00:33:10.676779 | controller -> localhost | ok: Runtime: 0:00:00.116283
2025-11-12 00:33:10.687491 | 
2025-11-12 00:33:10.687818 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-12 00:33:10.720895 | controller | ok
2025-11-12 00:33:10.737989 | controller | included: /var/lib/zuul/builds/1343c62253824f3ba2006942f45f7805/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-12 00:33:10.748668 | 
2025-11-12 00:33:10.748836 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-12 00:33:10.774101 | controller | skipping: Conditional result was False
2025-11-12 00:33:10.781169 | 
2025-11-12 00:33:10.781236 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-12 00:33:11.886515 | controller | changed
2025-11-12 00:33:11.899716 | 
2025-11-12 00:33:11.899878 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-12 00:33:12.573116 | controller | ok
2025-11-12 00:33:12.582559 | 
2025-11-12 00:33:12.582761 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-12 00:33:15.661429 | controller | changed
2025-11-12 00:33:15.682983 | 
2025-11-12 00:33:15.683250 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-12 00:33:18.868877 | controller | changed
2025-11-12 00:33:18.874993 | 
2025-11-12 00:33:18.875098 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-12 00:33:18.900657 | controller | skipping: Conditional result was False
2025-11-12 00:33:18.907320 | 
2025-11-12 00:33:18.907415 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-12 00:33:19.319658 | controller -> localhost | changed
2025-11-12 00:33:19.348188 | 
2025-11-12 00:33:19.348444 | TASK [add-build-sshkey : Add back temp key]
2025-11-12 00:33:19.602189 | controller -> localhost | Identity added: /var/lib/zuul/builds/1343c62253824f3ba2006942f45f7805/work/1343c62253824f3ba2006942f45f7805_id_rsa (zuul-build-sshkey)
2025-11-12 00:33:19.602538 | controller -> localhost | ok: Runtime: 0:00:00.007160
2025-11-12 00:33:19.612378 | 
2025-11-12 00:33:19.612511 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-12 00:33:20.546327 | controller | ok
2025-11-12 00:33:20.559520 | 
2025-11-12 00:33:20.559710 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-12 00:33:20.607866 | controller | skipping: Conditional result was False
2025-11-12 00:33:20.646771 | 
2025-11-12 00:33:20.647038 | TASK [Run add-build-sshkey role (ECDSA)]
2025-11-12 00:33:20.683171 | controller | ok
2025-11-12 00:33:20.703976 | 
2025-11-12 00:33:20.704125 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-12 00:33:20.993458 | controller -> localhost | ok
2025-11-12 00:33:21.010929 | 
2025-11-12 00:33:21.011193 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-12 00:33:21.051978 | controller | ok
2025-11-12 00:33:21.071719 | controller | included: /var/lib/zuul/builds/1343c62253824f3ba2006942f45f7805/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-12 00:33:21.086637 | 
2025-11-12 00:33:21.086911 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-12 00:33:21.390944 | controller -> localhost | Generating public/private ecdsa key pair.
2025-11-12 00:33:21.391216 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/1343c62253824f3ba2006942f45f7805/work/1343c62253824f3ba2006942f45f7805_id_ecdsa.
2025-11-12 00:33:21.391247 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/1343c62253824f3ba2006942f45f7805/work/1343c62253824f3ba2006942f45f7805_id_ecdsa.pub.
2025-11-12 00:33:21.391277 | controller -> localhost | The key fingerprint is:
2025-11-12 00:33:21.391297 | controller -> localhost | SHA256:I/EECZOmN+KVQKAubbMtMDneJr2zID4/Zscc1obGgVQ zuul-build-sshkey
2025-11-12 00:33:21.391318 | controller -> localhost | The key's randomart image is:
2025-11-12 00:33:21.391337 | controller -> localhost | +---[ECDSA 521]---+
2025-11-12 00:33:21.391355 | controller -> localhost | |.o. +E..         |
2025-11-12 00:33:21.391372 | controller -> localhost | |. ..o...         |
2025-11-12 00:33:21.391390 | controller -> localhost | |. .+... .        |
2025-11-12 00:33:21.391407 | controller -> localhost | |.oo.=. +         |
2025-11-12 00:33:21.391424 | controller -> localhost | |*o++..= S        |
2025-11-12 00:33:21.391442 | controller -> localhost | |o=++ * + .       |
2025-11-12 00:33:21.391458 | controller -> localhost | |.+o+* o          |
2025-11-12 00:33:21.391475 | controller -> localhost | |o.+*.+           |
2025-11-12 00:33:21.391492 | controller -> localhost | | .=+=            |
2025-11-12 00:33:21.391509 | controller -> localhost | +----[SHA256]-----+
2025-11-12 00:33:21.391562 | controller -> localhost | ok: Runtime: 0:00:00.008851
2025-11-12 00:33:21.398881 | 
2025-11-12 00:33:21.399026 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-12 00:33:21.430862 | controller | ok
2025-11-12 00:33:21.440679 | controller | included: /var/lib/zuul/builds/1343c62253824f3ba2006942f45f7805/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-12 00:33:21.450915 | 
2025-11-12 00:33:21.451026 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-12 00:33:21.476767 | controller | skipping: Conditional result was False
2025-11-12 00:33:21.484392 | 
2025-11-12 00:33:21.484492 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-12 00:33:22.382283 | controller | changed
2025-11-12 00:33:22.398300 | 
2025-11-12 00:33:22.398513 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-12 00:33:23.061802 | controller | ok
2025-11-12 00:33:23.069809 | 
2025-11-12 00:33:23.069925 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-12 00:33:26.261440 | controller | changed
2025-11-12 00:33:26.274876 | 
2025-11-12 00:33:26.275024 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-12 00:33:29.363990 | controller | changed
2025-11-12 00:33:29.380731 | 
2025-11-12 00:33:29.380940 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-12 00:33:29.408982 | controller | skipping: Conditional result was False
2025-11-12 00:33:29.424814 | 
2025-11-12 00:33:29.425033 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-12 00:33:29.729032 | controller -> localhost | changed
2025-11-12 00:33:29.743220 | 
2025-11-12 00:33:29.743300 | TASK [add-build-sshkey : Add back temp key]
2025-11-12 00:33:30.073430 | controller -> localhost | Identity added: /var/lib/zuul/builds/1343c62253824f3ba2006942f45f7805/work/1343c62253824f3ba2006942f45f7805_id_ecdsa (zuul-build-sshkey)
2025-11-12 00:33:30.073762 | controller -> localhost | ok: Runtime: 0:00:00.009450
2025-11-12 00:33:30.081432 | 
2025-11-12 00:33:30.081497 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-12 00:33:30.786779 | controller | ok
2025-11-12 00:33:30.800626 | 
2025-11-12 00:33:30.800855 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-12 00:33:30.830438 | controller | skipping: Conditional result was False
2025-11-12 00:33:30.860757 | 
2025-11-12 00:33:30.860974 | TASK [include_role : remove-zuul-sshkey]
2025-11-12 00:33:30.887755 | controller | skipping: Conditional result was False
2025-11-12 00:33:30.898326 | 
2025-11-12 00:33:30.898505 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-12 00:33:31.613079 | controller | ok: "logs"
2025-11-12 00:33:31.613419 | controller | ok: All items complete
2025-11-12 00:33:31.613458 | 
2025-11-12 00:33:32.331759 | controller | ok: "artifacts"
2025-11-12 00:33:33.006178 | controller | ok: "docs"
2025-11-12 00:33:33.015565 | 
2025-11-12 00:33:33.016711 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-12 00:33:33.751878 | controller | changed: "logs"
2025-11-12 00:33:34.386169 | controller | changed: "artifacts"
2025-11-12 00:33:35.078452 | controller | changed: "docs"
2025-11-12 00:33:35.139303 | 
2025-11-12 00:33:35.139472 | PLAY RECAP
2025-11-12 00:33:35.139525 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-11-12 00:33:35.139551 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-12 00:33:35.139569 | 
2025-11-12 00:33:35.290356 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-12 00:33:35.292275 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-12 00:33:35.870541 | 
2025-11-12 00:33:35.870703 | PLAY [all]
2025-11-12 00:33:35.892655 | 
2025-11-12 00:33:35.892773 | TASK [Install binary dependencies]
2025-11-12 00:33:35.979398 | controller | ok
2025-11-12 00:33:36.012655 | 
2025-11-12 00:33:36.012800 | TASK [bindep : Include find tasks]
2025-11-12 00:33:36.060384 | controller | ok
2025-11-12 00:33:36.076554 | controller | included: /var/lib/zuul/builds/1343c62253824f3ba2006942f45f7805/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-11-12 00:33:36.085142 | 
2025-11-12 00:33:36.085236 | TASK [bindep : Look for bindep.txt]
2025-11-12 00:33:37.101037 | controller | ok
2025-11-12 00:33:37.107051 | 
2025-11-12 00:33:37.107122 | TASK [bindep : Define bindep_file fact]
2025-11-12 00:33:37.131969 | controller | skipping: Conditional result was False
2025-11-12 00:33:37.138774 | 
2025-11-12 00:33:37.138847 | TASK [bindep : Look for other-requirements.txt]
2025-11-12 00:33:37.850434 | controller | ok
2025-11-12 00:33:37.864636 | 
2025-11-12 00:33:37.864920 | TASK [bindep : Define bindep_file fact]
2025-11-12 00:33:37.893377 | controller | skipping: Conditional result was False
2025-11-12 00:33:37.907002 | 
2025-11-12 00:33:37.907154 | TASK [bindep : Look for bindep fallback file]
2025-11-12 00:33:37.936010 | controller | skipping: Conditional result was False
2025-11-12 00:33:37.949750 | 
2025-11-12 00:33:37.949940 | TASK [bindep : Define bindep_file fact]
2025-11-12 00:33:37.979785 | controller | skipping: Conditional result was False
2025-11-12 00:33:37.993600 | 
2025-11-12 00:33:37.993866 | TASK [bindep : Include bindep tasks]
2025-11-12 00:33:38.022974 | controller | skipping: Conditional result was False
2025-11-12 00:33:38.036732 | 
2025-11-12 00:33:38.036889 | TASK [bindep : Include install tasks]
2025-11-12 00:33:38.065530 | controller | skipping: Conditional result was False
2025-11-12 00:33:38.080541 | 
2025-11-12 00:33:38.080733 | LOOP [bindep : Include package tasks]
2025-11-12 00:33:38.157951 | 
2025-11-12 00:33:38.158251 | TASK [Run test-setup role]
2025-11-12 00:33:38.188817 | controller | ok
2025-11-12 00:33:38.218085 | 
2025-11-12 00:33:38.218217 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-11-12 00:33:38.942720 | controller | ok
2025-11-12 00:33:38.956690 | 
2025-11-12 00:33:38.956864 | TASK [test-setup : Run tools/test-setup.sh]
2025-11-12 00:33:39.331138 | controller | skipping: Conditional result was False
2025-11-12 00:33:39.361619 | 
2025-11-12 00:33:39.361763 | PLAY RECAP
2025-11-12 00:33:39.361811 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-12 00:33:39.361838 | 
2025-11-12 00:33:39.501254 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-12 00:33:39.502249 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-12 00:33:40.165422 | 
2025-11-12 00:33:40.165591 | PLAY [controller]
2025-11-12 00:33:40.185922 | 
2025-11-12 00:33:40.186002 | TASK [Create the /root directory]
2025-11-12 00:33:41.339832 | controller | ok
2025-11-12 00:33:41.348308 | 
2025-11-12 00:33:41.348426 | TASK [Install glibc-langpack-en]
2025-11-12 00:33:49.460886 | controller | ok: Nothing to do
2025-11-12 00:33:49.467155 | 
2025-11-12 00:33:49.467242 | TASK [Ensure controller directory exists]
2025-11-12 00:33:50.272558 | controller | changed
2025-11-12 00:33:50.289030 | 
2025-11-12 00:33:50.289301 | TASK [Install container runtime]
2025-11-12 00:33:50.367939 | controller | ok
2025-11-12 00:33:50.423051 | 
2025-11-12 00:33:50.423180 | LOOP [ensure-docker : Gather variables for each operating system]
2025-11-12 00:33:50.481519 | controller | ok: "/var/lib/zuul/builds/1343c62253824f3ba2006942f45f7805/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-11-12 00:33:50.489148 | 
2025-11-12 00:33:50.489219 | TASK [ensure-docker : Sanity check for distro selection]
2025-11-12 00:33:50.516219 | controller | skipping: Conditional result was False
2025-11-12 00:33:50.525533 | 
2025-11-12 00:33:50.525693 | TASK [ensure-docker : Sanity check for distro install]
2025-11-12 00:33:50.561996 | controller | skipping: Conditional result was False
2025-11-12 00:33:50.571743 | 
2025-11-12 00:33:50.571843 | TASK [ensure-docker : Sanity check for upstream install]
2025-11-12 00:33:50.599356 | controller | skipping: Conditional result was False
2025-11-12 00:33:50.609121 | 
2025-11-12 00:33:50.609324 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-11-12 00:33:50.637038 | controller | skipping: Conditional result was False
2025-11-12 00:33:50.648213 | 
2025-11-12 00:33:50.648325 | TASK [ensure-docker : Include tasks for docker install]
2025-11-12 00:33:50.683769 | controller | ok
2025-11-12 00:33:50.696990 | controller | included: /var/lib/zuul/builds/1343c62253824f3ba2006942f45f7805/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-11-12 00:33:50.711517 | 
2025-11-12 00:33:50.711642 | TASK [ensure-docker : Not Implemented]
2025-11-12 00:33:50.738151 | controller | skipping: Conditional result was False
2025-11-12 00:33:50.748601 | 
2025-11-12 00:33:50.748712 | TASK [Set up docker mirrors]
2025-11-12 00:33:50.774552 | controller | ok
2025-11-12 00:33:50.803291 | 
2025-11-12 00:33:50.803375 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-11-12 00:33:50.838742 | controller | skipping: Conditional result was False
2025-11-12 00:33:50.846488 | 
2025-11-12 00:33:50.846578 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-11-12 00:33:50.882735 | controller | skipping: Conditional result was False
2025-11-12 00:33:50.944845 | 
2025-11-12 00:33:50.945017 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-11-12 00:33:50.990769 | controller | skipping: Conditional result was False
2025-11-12 00:33:50.998523 | 
2025-11-12 00:33:50.998706 | TASK [use-docker-mirror : Set up docker mirrors]
2025-11-12 00:33:51.034367 | controller | skipping: Conditional result was False
2025-11-12 00:33:51.046765 | 
2025-11-12 00:33:51.046864 | TASK [Add all repositories]
2025-11-12 00:33:51.069187 | controller | ok
2025-11-12 00:33:51.094056 | 
2025-11-12 00:33:51.094177 | LOOP [ensure-package-repositories : Install repositories]
2025-11-12 00:33:51.127493 | controller | ok: "/var/lib/zuul/builds/1343c62253824f3ba2006942f45f7805/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-11-12 00:33:51.127781 | controller | ok: All items complete
2025-11-12 00:33:51.127814 | 
2025-11-12 00:33:51.137604 | controller | included: /var/lib/zuul/builds/1343c62253824f3ba2006942f45f7805/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-11-12 00:33:51.143949 | 
2025-11-12 00:33:51.144010 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-11-12 00:33:52.033759 | controller | changed
2025-11-12 00:33:52.051710 | 
2025-11-12 00:33:52.051912 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-11-12 00:33:55.214949 | controller | changed:
2025-11-12 00:33:55.215507 | controller | {
2025-11-12 00:33:55.215575 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2025-11-12 00:33:55.215626 | controller | }
2025-11-12 00:33:55.238233 | 
2025-11-12 00:33:55.238369 | LOOP [ensure-package-repositories : Import GPG keys]
2025-11-12 00:33:56.567653 | controller | changed:
2025-11-12 00:33:56.567982 | controller | {
2025-11-12 00:33:56.568027 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2025-11-12 00:33:56.568063 | controller | }
2025-11-12 00:33:56.568107 | controller | changed: All items complete
2025-11-12 00:33:56.568138 | 
2025-11-12 00:33:56.580194 | 
2025-11-12 00:33:56.580424 | TASK [ensure-package-repositories : Remove GPG temp directory]
2025-11-12 00:33:57.280581 | controller | changed
2025-11-12 00:33:57.287575 | 
2025-11-12 00:33:57.287703 | LOOP [ensure-package-repositories : Add repositories]
2025-11-12 00:33:58.167444 | controller | changed:
2025-11-12 00:33:58.167874 | controller | {
2025-11-12 00:33:58.168084 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2025-11-12 00:33:58.168145 | controller |   "description": "Docker CE Stable - $basearch",
2025-11-12 00:33:58.168188 | controller |   "gpgcheck": true,
2025-11-12 00:33:58.168225 | controller |   "name": "docker-ce-stable"
2025-11-12 00:33:58.168263 | controller | }
2025-11-12 00:33:58.204603 | 
2025-11-12 00:33:58.204844 | TASK [ensure-docker : Install pre-reqs]
2025-11-12 00:34:03.185159 | controller | ok: Nothing to do
2025-11-12 00:34:03.194245 | 
2025-11-12 00:34:03.194430 | TASK [ensure-docker : Remove packages]
2025-11-12 00:34:06.350625 | controller | ok: Nothing to do
2025-11-12 00:34:06.361502 | 
2025-11-12 00:34:06.361644 | TASK [ensure-docker : Install upstream docker using package]
2025-11-12 00:35:31.762818 | controller | changed
2025-11-12 00:35:31.769335 | 
2025-11-12 00:35:31.769405 | TASK [ensure-docker : Setup docker]
2025-11-12 00:35:31.789428 | controller | ok
2025-11-12 00:35:31.800525 | controller | included: /var/lib/zuul/builds/1343c62253824f3ba2006942f45f7805/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2025-11-12 00:35:31.811800 | 
2025-11-12 00:35:31.811859 | TASK [ensure-docker : Ensure "docker" group exists]
2025-11-12 00:35:32.809575 | controller | ok
2025-11-12 00:35:32.823910 | 
2025-11-12 00:35:32.824055 | TASK [ensure-docker : Add user to docker group]
2025-11-12 00:35:34.045259 | controller | changed
2025-11-12 00:35:34.055583 | 
2025-11-12 00:35:34.055772 | TASK [Update docker daemon proxy configuration]
2025-11-12 00:35:34.106211 | controller | skipping: Conditional result was False
2025-11-12 00:35:34.115069 | 
2025-11-12 00:35:34.115240 | TASK [Lower default MTU]
2025-11-12 00:35:34.142487 | controller | skipping: Conditional result was False
2025-11-12 00:35:34.152841 | 
2025-11-12 00:35:34.153054 | TASK [ensure-docker : Get the status of the docket.socket unit]
2025-11-12 00:35:35.074619 | controller | inactive
2025-11-12 00:35:35.518636 | controller | ok: Runtime: 0:00:00.027695
2025-11-12 00:35:35.533284 | 
2025-11-12 00:35:35.533424 | TASK [ensure-docker : Get the status of the docket unit]
2025-11-12 00:35:36.413262 | controller | inactive
2025-11-12 00:35:36.907233 | controller | ok: Runtime: 0:00:00.025237
2025-11-12 00:35:36.924734 | 
2025-11-12 00:35:36.924882 | TASK [ensure-docker : Restart docker]
2025-11-12 00:35:36.970628 | Trigger a Docker restart
2025-11-12 00:35:36.978305 | 
2025-11-12 00:35:36.978386 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2025-11-12 00:35:37.021206 | 
2025-11-12 00:35:37.021367 | TASK [ensure-docker : Install docker-compose]
2025-11-12 00:35:37.050582 | controller | skipping: Conditional result was False
2025-11-12 00:35:37.058697 | 
2025-11-12 00:35:37.058773 | TASK [ensure-docker : Flush handlers before role exit]
2025-11-12 00:35:37.069639 | 
2025-11-12 00:35:37.069722 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2025-11-12 00:35:40.580272 | controller | changed
2025-11-12 00:35:40.594943 | 
2025-11-12 00:35:40.595045 | TASK [ensure-docker : Assure docker service is running]
2025-11-12 00:35:44.152151 | controller | changed
2025-11-12 00:35:44.168775 | 
2025-11-12 00:35:44.168913 | TASK [ensure-docker : Assure docker.socket service is running]
2025-11-12 00:35:45.834291 | controller | ok
2025-11-12 00:35:45.854570 | 
2025-11-12 00:35:45.854763 | TASK [ensure-docker : Correct group ownership on docker sock]
2025-11-12 00:35:46.641628 | controller | ok
2025-11-12 00:35:46.650339 | 
2025-11-12 00:35:46.650418 | TASK [ensure-docker : Validate ability to talk with docker]
2025-11-12 00:35:47.299283 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2025-11-12 00:35:47.515728 | controller | ok: Runtime: 0:00:00.039380
2025-11-12 00:35:47.530505 | 
2025-11-12 00:35:47.530751 | TASK [ensure-docker : Get version details]
2025-11-12 00:35:48.229550 | controller | Client: Docker Engine - Community
2025-11-12 00:35:48.231785 | controller |  Version:           24.0.7
2025-11-12 00:35:48.231810 | controller |  API version:       1.43
2025-11-12 00:35:48.231818 | controller |  Go version:        go1.20.10
2025-11-12 00:35:48.231835 | controller |  Git commit:        afdd53b
2025-11-12 00:35:48.231842 | controller |  Built:             Thu Oct 26 09:09:39 2023
2025-11-12 00:35:48.231849 | controller |  OS/Arch:           linux/amd64
2025-11-12 00:35:48.231858 | controller |  Context:           default
2025-11-12 00:35:48.231868 | controller |
2025-11-12 00:35:48.231875 | controller | Server: Docker Engine - Community
2025-11-12 00:35:48.231882 | controller |  Engine:
2025-11-12 00:35:48.231907 | controller |   Version:          24.0.7
2025-11-12 00:35:48.233523 | controller |   API version:      1.43 (minimum version 1.12)
2025-11-12 00:35:48.233539 | controller |   Go version:       go1.20.10
2025-11-12 00:35:48.233545 | controller |   Git commit:       311b9ff
2025-11-12 00:35:48.233552 | controller |   Built:            Thu Oct 26 09:08:11 2023
2025-11-12 00:35:48.233558 | controller |   OS/Arch:          linux/amd64
2025-11-12 00:35:48.233565 | controller |   Experimental:     false
2025-11-12 00:35:48.233571 | controller |  containerd:
2025-11-12 00:35:48.233577 | controller |   Version:          1.6.25
2025-11-12 00:35:48.233582 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2025-11-12 00:35:48.233588 | controller |  runc:
2025-11-12 00:35:48.233594 | controller |   Version:          1.1.10
2025-11-12 00:35:48.233600 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2025-11-12 00:35:48.233610 | controller |  docker-init:
2025-11-12 00:35:48.233616 | controller |   Version:          0.19.0
2025-11-12 00:35:48.233622 | controller |   GitCommit:        de40ad0
2025-11-12 00:35:48.416456 | controller | ok: Runtime: 0:00:00.053467
2025-11-12 00:35:48.430913 | 
2025-11-12 00:35:48.431058 | TASK [ensure-docker : Dump installed docker details]
2025-11-12 00:35:48.473724 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version:           24.0.7\n API version:       1.43\n Go version:        go1.20.10\n Git commit:        afdd53b\n Built:             Thu Oct 26 09:09:39 2023\n OS/Arch:           linux/amd64\n Context:           default\n\nServer: Docker Engine - Community\n Engine:\n  Version:          24.0.7\n  API version:      1.43 (minimum version 1.12)\n  Go version:       go1.20.10\n  Git commit:       311b9ff\n  Built:            Thu Oct 26 09:08:11 2023\n  OS/Arch:          linux/amd64\n  Experimental:     false\n containerd:\n  Version:          1.6.25\n  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n  Version:          1.1.10\n  GitCommit:        v1.1.10-0-g18a0cb0\n docker-init:\n  Version:          0.19.0\n  GitCommit:        de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2025-11-12 00:35:48.180640', 'end': '2025-11-12 00:35:48.234107', 'delta': '0:00:00.053467', 'msg': '', 'zuul_log_id': 'fa163efc-24cc-572f-0f3c-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version:           24.0.7', ' API version:       1.43', ' Go version:        go1.20.10', ' Git commit:        afdd53b', ' Built:             Thu Oct 26 09:09:39 2023', ' OS/Arch:           linux/amd64', ' Context:           default', '', 'Server: Docker Engine - Community', ' Engine:', '  Version:          24.0.7', '  API version:      1.43 (minimum version 1.12)', '  Go version:       go1.20.10', '  Git commit:       311b9ff', '  Built:            Thu Oct 26 09:08:11 2023', '  OS/Arch:          linux/amd64', '  Experimental:     false', ' containerd:', '  Version:          1.6.25', '  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', '  Version:          1.1.10', '  GitCommit:        v1.1.10-0-g18a0cb0', ' docker-init:', '  Version:          0.19.0', '  GitCommit:        de40ad0'], 'stderr_lines': [], 'failed': False}
2025-11-12 00:35:48.491028 | 
2025-11-12 00:35:48.491226 | TASK [Use buildset registry]
2025-11-12 00:35:48.518879 | controller | skipping: Conditional result was False
2025-11-12 00:35:48.536167 | 
2025-11-12 00:35:48.536559 | TASK [Ensure python3.8 is present]
2025-11-12 00:35:48.562697 | controller | skipping: Conditional result was False
2025-11-12 00:35:48.569932 | 
2025-11-12 00:35:48.570004 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-11-12 00:35:48.593624 | controller | ok
2025-11-12 00:35:48.613905 | 
2025-11-12 00:35:48.614003 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-11-12 00:35:54.170161 | controller | ok: Nothing to do
2025-11-12 00:35:54.185080 | 
2025-11-12 00:35:54.185231 | TASK [our-ensure-python : Also install python3-devel]
2025-11-12 00:36:10.428823 | controller | changed
2025-11-12 00:36:10.452802 | 
2025-11-12 00:36:10.452994 | TASK [Run ensure-virtualenv role]
2025-11-12 00:36:10.484817 | controller | ok
2025-11-12 00:36:10.522170 | 
2025-11-12 00:36:10.522345 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-11-12 00:36:11.319466 | controller | /usr/bin/virtualenv
2025-11-12 00:36:11.884460 | controller | ok: Runtime: 0:00:00.007685
2025-11-12 00:36:11.897964 | 
2025-11-12 00:36:11.898162 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-11-12 00:36:11.923406 | controller | skipping: Conditional result was False
2025-11-12 00:36:11.923762 | controller | changed: All items complete
2025-11-12 00:36:11.923798 | 
2025-11-12 00:36:11.953659 | 
2025-11-12 00:36:11.953909 | TASK [Find the full path of the Python interpreter]
2025-11-12 00:36:12.704075 | controller | /usr/bin/python3.8
2025-11-12 00:36:13.321538 | controller | ok
2025-11-12 00:36:13.334801 | 
2025-11-12 00:36:13.334956 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-11-12 00:36:15.535276 | controller | created virtual environment CPython3.8.18.final.0-64 in 1095ms
2025-11-12 00:36:15.663246 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-11-12 00:36:15.663297 | controller |   seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv)
2025-11-12 00:36:15.663312 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-11-12 00:36:15.663333 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-11-12 00:36:16.198799 | controller | changed
2025-11-12 00:36:16.211383 | 
2025-11-12 00:36:16.211521 | TASK [Set selinux package]
2025-11-12 00:36:16.251766 | controller | ok
2025-11-12 00:36:16.264094 | 
2025-11-12 00:36:16.264225 | TASK [Set selinux package (Fedora)]
2025-11-12 00:36:16.313988 | controller | ok
2025-11-12 00:36:16.326125 | 
2025-11-12 00:36:16.326258 | TASK [Install selinux into virtualenv]
2025-11-12 00:36:23.054272 | controller | Collecting selinux-please-lie-to-me
2025-11-12 00:36:23.141562 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-11-12 00:36:23.835912 | controller | Collecting setuptools<50.0.0
2025-11-12 00:36:23.842945 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-11-12 00:36:23.918740 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 11.2 MB/s eta 0:00:00
2025-11-12 00:36:24.058145 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-11-12 00:36:24.058528 | controller |   Attempting uninstall: setuptools
2025-11-12 00:36:24.060775 | controller |     Found existing installation: setuptools 62.6.0
2025-11-12 00:36:24.201200 | controller |     Uninstalling setuptools-62.6.0:
2025-11-12 00:36:24.218998 | controller |       Successfully uninstalled setuptools-62.6.0
2025-11-12 00:36:24.941280 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-11-12 00:36:25.166247 | controller |
2025-11-12 00:36:25.436641 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-11-12 00:36:25.436688 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-11-12 00:36:25.714309 | controller | ok: Runtime: 0:00:07.547491
2025-11-12 00:36:25.727050 | 
2025-11-12 00:36:25.727209 | TASK [Install pytest-forked into virtualenv]
2025-11-12 00:36:27.241370 | controller | Collecting pytest-forked
2025-11-12 00:36:27.298174 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-11-12 00:36:27.507522 | controller | Collecting pytest>=3.10
2025-11-12 00:36:27.515500 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2025-11-12 00:36:27.562402 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 8.1 MB/s eta 0:00:00
2025-11-12 00:36:27.638749 | controller | Collecting py
2025-11-12 00:36:27.649364 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-11-12 00:36:27.663315 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 9.7 MB/s eta 0:00:00
2025-11-12 00:36:27.723970 | controller | Collecting iniconfig
2025-11-12 00:36:27.729761 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-11-12 00:36:27.794800 | controller | Collecting pluggy<2,>=1.5
2025-11-12 00:36:27.799832 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-11-12 00:36:27.880736 | controller | Collecting packaging
2025-11-12 00:36:27.889930 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-11-12 00:36:27.900994 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 7.6 MB/s eta 0:00:00
2025-11-12 00:36:27.955563 | controller | Collecting exceptiongroup>=1.0.0rc8
2025-11-12 00:36:27.960831 | controller |   Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB)
2025-11-12 00:36:28.042240 | controller | Collecting tomli>=1
2025-11-12 00:36:28.049079 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2025-11-12 00:36:28.140553 | controller | Collecting typing-extensions>=4.6.0
2025-11-12 00:36:28.145835 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2025-11-12 00:36:28.160622 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 3.2 MB/s eta 0:00:00
2025-11-12 00:36:28.265917 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-11-12 00:36:29.144555 | controller | Successfully installed exceptiongroup-1.3.0 iniconfig-2.1.0 packaging-25.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.3.0 typing-extensions-4.13.2
2025-11-12 00:36:29.149454 | controller |
2025-11-12 00:36:29.357107 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-11-12 00:36:29.357161 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-11-12 00:36:29.642735 | controller | ok: Runtime: 0:00:02.820357
2025-11-12 00:36:29.654028 | 
2025-11-12 00:36:29.655818 | TASK [Update pip]
2025-11-12 00:36:31.909754 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2025-11-12 00:36:32.230968 | controller | Collecting pip
2025-11-12 00:36:32.293890 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2025-11-12 00:36:32.363633 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 31.1 MB/s eta 0:00:00
2025-11-12 00:36:32.610117 | controller | Installing collected packages: pip
2025-11-12 00:36:32.613704 | controller |   Attempting uninstall: pip
2025-11-12 00:36:32.615056 | controller |     Found existing installation: pip 22.2.2
2025-11-12 00:36:33.066041 | controller |     Uninstalling pip-22.2.2:
2025-11-12 00:36:33.140254 | controller |       Successfully uninstalled pip-22.2.2
2025-11-12 00:36:36.119810 | controller | Successfully installed pip-25.0.1
2025-11-12 00:36:36.549350 | controller | ok: Runtime: 0:00:05.513577
2025-11-12 00:36:36.563544 | 
2025-11-12 00:36:36.563739 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-11-12 00:36:37.745394 | controller | changed
2025-11-12 00:36:37.752796 | 
2025-11-12 00:36:37.753172 | TASK [Install ansible into virtualenv]
2025-11-12 00:36:39.713246 | controller | Processing ./src/github.com/ansible/ansible
2025-11-12 00:36:39.715674 | controller |   Preparing metadata (setup.py): started
2025-11-12 00:36:40.551314 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-11-12 00:36:40.617537 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2025-11-12 00:36:40.634042 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-11-12 00:36:40.723823 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2025-11-12 00:36:40.731551 | controller |   Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.1 kB)
2025-11-12 00:36:41.184939 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2025-11-12 00:36:41.193670 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-11-12 00:36:41.218920 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (25.0)
2025-11-12 00:36:41.452663 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2025-11-12 00:36:41.457623 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2025-11-12 00:36:41.469147 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.8/site-packages (from cryptography->ansible-base==2.10.17.post0) (4.13.2)
2025-11-12 00:36:41.587070 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2025-11-12 00:36:41.597928 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2025-11-12 00:36:41.628417 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2025-11-12 00:36:41.634450 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-11-12 00:36:41.658808 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2025-11-12 00:36:41.709278 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 106.2 MB/s eta 0:00:00
2025-11-12 00:36:41.715685 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-11-12 00:36:41.729898 | controller | Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (806 kB)
2025-11-12 00:36:41.750519 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 37.3 MB/s eta 0:00:00
2025-11-12 00:36:41.756175 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2025-11-12 00:36:41.773329 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2025-11-12 00:36:41.784977 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-11-12 00:36:41.813884 | controller | Building wheels for collected packages: ansible-base
2025-11-12 00:36:41.815930 | controller |   Building wheel for ansible-base (setup.py): started
2025-11-12 00:36:44.137775 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2025-11-12 00:36:44.141129 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=a90985b68f3243bff02002cca14fca46c552bfdb3e9765262c855ddb311bc43c
2025-11-12 00:36:44.141437 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-osy9k7he/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2025-11-12 00:36:44.155818 | controller | Successfully built ansible-base
2025-11-12 00:36:44.217112 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2025-11-12 00:36:47.190752 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-base-2.10.17.post0 cffi-1.17.1 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23
2025-11-12 00:36:47.654533 | controller | ok: Runtime: 0:00:08.728375
2025-11-12 00:36:47.668937 | 
2025-11-12 00:36:47.669103 | TASK [Setup download-artifact-fork role]
2025-11-12 00:36:47.702387 | controller | ok
2025-11-12 00:36:47.737352 | 
2025-11-12 00:36:47.737544 | TASK [download-artifact-fork : Parse inventory response]
2025-11-12 00:36:47.794148 | controller | ok
2025-11-12 00:36:47.803086 | 
2025-11-12 00:36:47.803200 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-11-12 00:36:47.850740 | controller | skipping: Conditional result was False
2025-11-12 00:36:47.865651 | 
2025-11-12 00:36:47.865856 | TASK [download-artifact-fork : Parse build response]
2025-11-12 00:36:47.905380 | controller | skipping: Conditional result was False
2025-11-12 00:36:47.920661 | 
2025-11-12 00:36:47.920888 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-11-12 00:36:48.775655 | controller | changed
2025-11-12 00:36:48.791434 | 
2025-11-12 00:36:48.791611 | LOOP [download-artifact-fork : Download archive by type]
2025-11-12 00:36:48.826419 | controller | skipping: Conditional result was False
2025-11-12 00:36:48.827040 | 
2025-11-12 00:36:51.709014 | controller | changed: OK (20196 bytes)
2025-11-12 00:36:51.730147 | 
2025-11-12 00:36:51.730309 | TASK [deploy-artifacts : define __collections]
2025-11-12 00:36:51.752633 | controller | ok
2025-11-12 00:36:51.759948 | 
2025-11-12 00:36:51.760085 | LOOP [deploy-artifacts : Create list of collections]
2025-11-12 00:36:51.785883 | controller | skipping: Conditional result was False
2025-11-12 00:36:51.795495 | controller | ok:
2025-11-12 00:36:51.795627 | controller | {
2025-11-12 00:36:51.795657 | controller |   "branch": "main",
2025-11-12 00:36:51.795697 | controller |   "job": "build-ansible-collection",
2025-11-12 00:36:51.795721 | controller |   "metadata": {
2025-11-12 00:36:51.795738 | controller |     "type": "ansible_collection",
2025-11-12 00:36:51.795759 | controller |     "version": "5.0.0"
2025-11-12 00:36:51.795782 | controller |   },
2025-11-12 00:36:51.795800 | controller |   "name": "ansible.network",
2025-11-12 00:36:51.795816 | controller |   "project": "ansible-collections/ansible.network",
2025-11-12 00:36:51.795831 | controller |   "url": "https://4c33b2993624b40a9315-213a12a90a6a84a5478d927a6e158113.ssl.cf5.rackcdn.com/ansible/489b571d4e1047bd9626aaa318ddc40d/artifacts/ansible-network-5.0.0.tar.gz"
2025-11-12 00:36:51.795854 | controller | }
2025-11-12 00:36:51.816927 | 
2025-11-12 00:36:51.817176 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-11-12 00:36:53.589923 | controller | Starting galaxy collection install process
2025-11-12 00:36:53.590597 | controller | Process install dependency map
2025-11-12 00:37:05.487755 | controller | Starting collection install process
2025-11-12 00:37:05.497900 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2025-11-12 00:37:05.498243 | controller | ansible.network (5.0.0) was installed successfully
2025-11-12 00:37:07.055201 | controller | Installing 'ansible.netcommon:8.2.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2025-11-12 00:37:07.055255 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-8.2.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-41526o2sgi8y/tmp_fnhmo2h
2025-11-12 00:37:07.055280 | controller | ansible.netcommon (8.2.0) was installed successfully
2025-11-12 00:37:07.057465 | controller | Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2025-11-12 00:37:07.057727 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-utils-6.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-41526o2sgi8y/tmp_fnhmo2h
2025-11-12 00:37:09.011879 | controller | ansible.utils (6.0.0) was installed successfully
2025-11-12 00:37:09.012055 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2025-11-12 00:37:09.012067 | 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-41526o2sgi8y/tmp_fnhmo2h
2025-11-12 00:37:11.598919 | controller | arista.eos (12.0.0) was installed successfully
2025-11-12 00:37:11.599214 | controller | Installing 'cisco.ios:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2025-11-12 00:37:11.599234 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-41526o2sgi8y/tmp_fnhmo2h
2025-11-12 00:37:14.332829 | controller | cisco.ios (11.1.1) was installed successfully
2025-11-12 00:37:14.332880 | controller | Installing 'cisco.iosxr:12.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2025-11-12 00:37:14.333112 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.1.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-41526o2sgi8y/tmp_fnhmo2h
2025-11-12 00:37:17.095469 | controller | cisco.iosxr (12.1.0) was installed successfully
2025-11-12 00:37:17.095516 | controller | Installing 'cisco.nxos:11.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2025-11-12 00:37:17.095722 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-41526o2sgi8y/tmp_fnhmo2h
2025-11-12 00:37:21.317704 | controller | cisco.nxos (11.1.0) was installed successfully
2025-11-12 00:37:21.318114 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2025-11-12 00:37:21.318149 | 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-41526o2sgi8y/tmp_fnhmo2h
2025-11-12 00:37:24.035378 | controller | junipernetworks.junos (11.0.0) was installed successfully
2025-11-12 00:37:24.725712 | controller | ok: Runtime: 0:00:31.464915
2025-11-12 00:37:24.753153 | 
2025-11-12 00:37:24.753446 | TASK [podman may need iptables kernel modules]
2025-11-12 00:37:26.160200 | controller | ok: Runtime: 0:00:00.007495
2025-11-12 00:37:26.207460 | 
2025-11-12 00:37:26.207553 | PLAY RECAP
2025-11-12 00:37:26.207613 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2025-11-12 00:37:26.207642 | 
2025-11-12 00:37:26.411983 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-12 00:37:26.414919 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-11-12 00:37:27.177399 | 
2025-11-12 00:37:27.177581 | PLAY [controller]
2025-11-12 00:37:27.200127 | 
2025-11-12 00:37:27.200277 | TASK [Copy the galaxy.yml on the controller]
2025-11-12 00:37:30.864076 | controller | changed
2025-11-12 00:37:30.876366 | 
2025-11-12 00:37:30.876525 | TASK [Load information from galaxy.yml]
2025-11-12 00:37:30.916749 | controller | ok
2025-11-12 00:37:30.928706 | 
2025-11-12 00:37:30.928962 | LOOP [Enable FIPS mode]
2025-11-12 00:37:30.957069 | 
2025-11-12 00:37:30.957360 | LOOP [ansible-test : Include branch specific variables.]
2025-11-12 00:37:30.993482 | controller | ok: "/var/lib/zuul/builds/1343c62253824f3ba2006942f45f7805/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2025-11-12 00:37:30.993769 | 
2025-11-12 00:37:31.009530 | 
2025-11-12 00:37:31.009748 | TASK [ansible-test : Setup --skip-tags for test_options]
2025-11-12 00:37:31.067340 | controller | ok
2025-11-12 00:37:31.077129 | 
2025-11-12 00:37:31.077238 | TASK [ansible-test : Enable --inventory for network-integration]
2025-11-12 00:37:31.114175 | controller | skipping: Conditional result was False
2025-11-12 00:37:31.125615 | 
2025-11-12 00:37:31.125763 | TASK [ansible-test : Enable --retry-on-error]
2025-11-12 00:37:31.152199 | controller | skipping: Conditional result was False
2025-11-12 00:37:31.162694 | 
2025-11-12 00:37:31.162779 | TASK [ansible-test : Enable --continue-on-error]
2025-11-12 00:37:31.217541 | controller | ok
2025-11-12 00:37:31.223917 | 
2025-11-12 00:37:31.223986 | TASK [ansible-test : Enable --requirements]
2025-11-12 00:37:31.249135 | controller | skipping: Conditional result was False
2025-11-12 00:37:31.257652 | 
2025-11-12 00:37:31.257841 | TASK [ansible-test : Adjust options for unit tests]
2025-11-12 00:37:31.284095 | controller | skipping: Conditional result was False
2025-11-12 00:37:31.292454 | 
2025-11-12 00:37:31.292619 | TASK [ansible-test : Install requirements with unit tests]
2025-11-12 00:37:31.337215 | controller | ok
2025-11-12 00:37:31.381727 | 
2025-11-12 00:37:31.381913 | TASK [ansible-test : Set --skip-test]
2025-11-12 00:37:31.437849 | controller | skipping: Conditional result was False
2025-11-12 00:37:31.445935 | 
2025-11-12 00:37:31.446043 | TASK [ansible-test : Reduce verbosity]
2025-11-12 00:37:31.476183 | controller | ok
2025-11-12 00:37:31.482028 | 
2025-11-12 00:37:31.482094 | TASK [ansible-test : Setup --python option]
2025-11-12 00:37:31.505787 | controller | skipping: Conditional result was False
2025-11-12 00:37:31.512874 | 
2025-11-12 00:37:31.512944 | TASK [ansible-test : Setup --docker option]
2025-11-12 00:37:31.542418 | controller | ok
2025-11-12 00:37:31.549727 | 
2025-11-12 00:37:31.549794 | TASK [ansible-test : check if MANIFEST.json exists]
2025-11-12 00:37:32.824840 | controller | ok
2025-11-12 00:37:32.838909 | 
2025-11-12 00:37:32.839151 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2025-11-12 00:37:32.867915 | controller | skipping: Conditional result was False
2025-11-12 00:37:32.883759 | 
2025-11-12 00:37:32.884008 | TASK [ansible-test : Load information from MANIFEST.json]
2025-11-12 00:37:32.913117 | controller | skipping: Conditional result was False
2025-11-12 00:37:32.931755 | 
2025-11-12 00:37:32.931999 | TASK [ansible-test : Setup location of project for integration tests]
2025-11-12 00:37:32.960374 | controller | skipping: Conditional result was False
2025-11-12 00:37:32.976049 | 
2025-11-12 00:37:32.976277 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2025-11-12 00:37:34.236021 | controller | ok
2025-11-12 00:37:34.251217 | 
2025-11-12 00:37:34.251405 | TASK [ansible-test : Load information from galaxy.yml]
2025-11-12 00:37:34.316129 | controller | ok
2025-11-12 00:37:34.330511 | 
2025-11-12 00:37:34.330743 | TASK [ansible-test : Setup location of project for integration tests]
2025-11-12 00:37:34.391389 | controller | ok
2025-11-12 00:37:34.403431 | 
2025-11-12 00:37:34.403565 | TASK [ansible-test : Setup minimum test requirements]
2025-11-12 00:37:34.438819 | controller | ok
2025-11-12 00:37:34.447017 | 
2025-11-12 00:37:34.447102 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2025-11-12 00:37:35.460467 | controller | ok
2025-11-12 00:37:35.478205 | 
2025-11-12 00:37:35.478407 | TASK [ansible-test : Install python requirements]
2025-11-12 00:37:36.972246 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-11-12 00:37:36.990697 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2025-11-12 00:37:37.027576 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-11-12 00:37:37.033761 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2025-11-12 00:37:37.051704 | controller | Requirement already satisfied: pyyaml in ./venv/lib64/python3.8/site-packages (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1)) (6.0.3)
2025-11-12 00:37:37.058822 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2025-11-12 00:37:37.076758 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2025-11-12 00:37:37.136168 | controller | Installing collected packages: pathspec, yamllint
2025-11-12 00:37:37.257667 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2025-11-12 00:37:37.856996 | controller | ok: Runtime: 0:00:01.051517
2025-11-12 00:37:37.866574 | 
2025-11-12 00:37:37.866790 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2025-11-12 00:37:38.792764 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2025-11-12 00:37:39.231728 | controller | ERROR
2025-11-12 00:37:39.232158 | controller | {
2025-11-12 00:37:39.232234 | controller |   "delta": "0:00:00.011001",
2025-11-12 00:37:39.232278 | controller |   "end": "2025-11-12 00:37:38.793014",
2025-11-12 00:37:39.232316 | controller |   "msg": "non-zero return code",
2025-11-12 00:37:39.232352 | controller |   "rc": 1,
2025-11-12 00:37:39.232386 | controller |   "start": "2025-11-12 00:37:38.782013"
2025-11-12 00:37:39.232421 | controller | }
2025-11-12 00:37:39.232469 | controller | ERROR: Ignoring Errors
2025-11-12 00:37:39.245972 | 
2025-11-12 00:37:39.246105 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2025-11-12 00:37:39.625357 | controller | skipping: Conditional result was False
2025-11-12 00:37:39.636230 | 
2025-11-12 00:37:39.636416 | TASK [ansible-test : Enable persistent connection logging]
2025-11-12 00:37:40.940615 | controller | changed: section and option added
2025-11-12 00:37:40.956539 | 
2025-11-12 00:37:40.956807 | TASK [ansible-test : Set persistent ssh_type value]
2025-11-12 00:37:42.430364 | controller | changed: option added
2025-11-12 00:37:42.443220 | 
2025-11-12 00:37:42.443364 | TASK [ansible-test : Set ansible log path]
2025-11-12 00:37:43.448573 | controller | changed: section and option added
2025-11-12 00:37:43.467719 | 
2025-11-12 00:37:43.467933 | TASK [ansible-test : Identify the changed targets]
2025-11-12 00:37:43.846737 | controller | skipping: Conditional result was False
2025-11-12 00:37:43.867932 | 
2025-11-12 00:37:43.868185 | TASK [ansible-test : set_fact]
2025-11-12 00:37:43.987990 | controller | skipping: Conditional result was False
2025-11-12 00:37:44.002334 | 
2025-11-12 00:37:44.002498 | TASK [ansible-test : Set the targets]
2025-11-12 00:37:44.102214 | controller | ok
2025-11-12 00:37:44.109773 | 
2025-11-12 00:37:44.110041 | TASK [ansible-test : set_fact]
2025-11-12 00:37:44.136026 | controller | skipping: Conditional result was False
2025-11-12 00:37:44.149432 | 
2025-11-12 00:37:44.149596 | TASK [ansible-test : List the targets]
2025-11-12 00:37:44.543509 | controller | skipping: Conditional result was False
2025-11-12 00:37:44.550635 | 
2025-11-12 00:37:44.550760 | TASK [ansible-test : set_fact]
2025-11-12 00:37:44.585958 | controller | skipping: Conditional result was False
2025-11-12 00:37:44.595461 | 
2025-11-12 00:37:44.595563 | TASK [ansible-test : set_fact]
2025-11-12 00:37:44.622181 | controller | skipping: Conditional result was False
2025-11-12 00:37:44.632074 | 
2025-11-12 00:37:44.632209 | LOOP [ansible-test : set_fact]
2025-11-12 00:37:44.723619 | 
2025-11-12 00:37:44.723984 | TASK [ansible-test : set_fact]
2025-11-12 00:37:44.752372 | controller | skipping: Conditional result was False
2025-11-12 00:37:44.765265 | 
2025-11-12 00:37:44.765420 | TASK [ansible-test : debug]
2025-11-12 00:37:44.794457 | controller | skipping: Conditional result was False
2025-11-12 00:37:44.807278 | 
2025-11-12 00:37:44.807452 | TASK [ansible-test : debug]
2025-11-12 00:37:44.841266 | About to run: ansible-test sanity --requirements --docker -v
2025-11-12 00:37:44.847591 | 
2025-11-12 00:37:44.847726 | TASK [ansible-test : Run the test suite]
2025-11-12 00:37:46.562615 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2025-11-12 00:37:46.655851 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2025-11-12 00:37:48.274899 | controller | 2.9.0: Pulling from ansible/default-test-container
2025-11-12 00:37:48.274947 | controller | 171857c49d0f: Pulling fs layer
2025-11-12 00:37:48.274971 | controller | 419640447d26: Pulling fs layer
2025-11-12 00:37:48.274979 | controller | 61e52f862619: Pulling fs layer
2025-11-12 00:37:48.274986 | controller | 851de0426e26: Pulling fs layer
2025-11-12 00:37:48.274997 | controller | 37425716ea5b: Pulling fs layer
2025-11-12 00:37:48.275006 | controller | cd9b0c439616: Pulling fs layer
2025-11-12 00:37:48.275017 | controller | d4612dbd8855: Pulling fs layer
2025-11-12 00:37:48.275023 | controller | 554699364730: Pulling fs layer
2025-11-12 00:37:48.275029 | controller | 1ff15d4bdb3e: Pulling fs layer
2025-11-12 00:37:48.275047 | controller | 5f2e4724adea: Pulling fs layer
2025-11-12 00:37:48.275053 | controller | a02d9a95ccb8: Pulling fs layer
2025-11-12 00:37:48.275062 | controller | 989f4ce370cb: Pulling fs layer
2025-11-12 00:37:48.275068 | controller | 0d7f52cdf9df: Pulling fs layer
2025-11-12 00:37:48.275074 | controller | c423742351d5: Pulling fs layer
2025-11-12 00:37:48.275080 | controller | 109236341d21: Pulling fs layer
2025-11-12 00:37:48.275086 | controller | 07a2f61c96b8: Pulling fs layer
2025-11-12 00:37:48.275093 | controller | 041f122f27ae: Pulling fs layer
2025-11-12 00:37:48.275099 | controller | ca6f5098b12e: Pulling fs layer
2025-11-12 00:37:48.275105 | controller | 1eb3e7b2afd9: Pulling fs layer
2025-11-12 00:37:48.275111 | controller | 18a0b750727a: Pulling fs layer
2025-11-12 00:37:48.275117 | controller | 17f2f596716c: Pulling fs layer
2025-11-12 00:37:48.275125 | controller | f35a9998a9bd: Pulling fs layer
2025-11-12 00:37:48.275131 | controller | fdfc10566405: Pulling fs layer
2025-11-12 00:37:48.275137 | controller | 1ff1c442c5fe: Pulling fs layer
2025-11-12 00:37:48.275143 | controller | a3443c4a17ed: Pulling fs layer
2025-11-12 00:37:48.275149 | controller | 0edcf9fb2b15: Pulling fs layer
2025-11-12 00:37:48.275155 | controller | 5af43b69e845: Pulling fs layer
2025-11-12 00:37:48.275195 | controller | 1651773effb2: Pulling fs layer
2025-11-12 00:37:48.275208 | controller | 79e23c5c0fe4: Pulling fs layer
2025-11-12 00:37:48.275219 | controller | e5058b1faaa6: Pulling fs layer
2025-11-12 00:37:48.275225 | controller | 846775490569: Pulling fs layer
2025-11-12 00:37:48.275231 | controller | 851de0426e26: Waiting
2025-11-12 00:37:48.275237 | controller | 37425716ea5b: Waiting
2025-11-12 00:37:48.275243 | controller | cd9b0c439616: Waiting
2025-11-12 00:37:48.275249 | controller | d4612dbd8855: Waiting
2025-11-12 00:37:48.275267 | controller | 554699364730: Waiting
2025-11-12 00:37:48.275273 | controller | 1ff15d4bdb3e: Waiting
2025-11-12 00:37:48.275282 | controller | 5f2e4724adea: Waiting
2025-11-12 00:37:48.275288 | controller | a02d9a95ccb8: Waiting
2025-11-12 00:37:48.275294 | controller | 989f4ce370cb: Waiting
2025-11-12 00:37:48.275300 | controller | 0d7f52cdf9df: Waiting
2025-11-12 00:37:48.275306 | controller | c423742351d5: Waiting
2025-11-12 00:37:48.275312 | controller | 109236341d21: Waiting
2025-11-12 00:37:48.275318 | controller | 07a2f61c96b8: Waiting
2025-11-12 00:37:48.275323 | controller | 041f122f27ae: Waiting
2025-11-12 00:37:48.275330 | controller | ca6f5098b12e: Waiting
2025-11-12 00:37:48.275335 | controller | 1eb3e7b2afd9: Waiting
2025-11-12 00:37:48.275341 | controller | 18a0b750727a: Waiting
2025-11-12 00:37:48.275347 | controller | 17f2f596716c: Waiting
2025-11-12 00:37:48.275356 | controller | f35a9998a9bd: Waiting
2025-11-12 00:37:48.275362 | controller | fdfc10566405: Waiting
2025-11-12 00:37:48.275368 | controller | 1ff1c442c5fe: Waiting
2025-11-12 00:37:48.275374 | controller | a3443c4a17ed: Waiting
2025-11-12 00:37:48.275379 | controller | 0edcf9fb2b15: Waiting
2025-11-12 00:37:48.275385 | controller | 5af43b69e845: Waiting
2025-11-12 00:37:48.275394 | controller | 1651773effb2: Waiting
2025-11-12 00:37:48.681027 | controller | 79e23c5c0fe4: Waiting
2025-11-12 00:37:48.681074 | controller | e5058b1faaa6: Waiting
2025-11-12 00:37:48.681083 | controller | 846775490569: Waiting
2025-11-12 00:37:48.681101 | controller | 419640447d26: Verifying Checksum
2025-11-12 00:37:48.687372 | controller | 419640447d26: Download complete
2025-11-12 00:37:48.687441 | controller | 61e52f862619: Verifying Checksum
2025-11-12 00:37:48.938596 | controller | 61e52f862619: Download complete
2025-11-12 00:37:48.938653 | controller | 171857c49d0f: Verifying Checksum
2025-11-12 00:37:49.737278 | controller | 171857c49d0f: Download complete
2025-11-12 00:37:49.738372 | controller | cd9b0c439616: Verifying Checksum
2025-11-12 00:37:49.762271 | controller | cd9b0c439616: Download complete
2025-11-12 00:37:49.762324 | controller | 851de0426e26: Verifying Checksum
2025-11-12 00:37:50.940841 | controller | 851de0426e26: Download complete
2025-11-12 00:37:50.940901 | controller | d4612dbd8855: Verifying Checksum
2025-11-12 00:37:52.081080 | controller | d4612dbd8855: Download complete
2025-11-12 00:37:52.081144 | controller | 1ff15d4bdb3e: Verifying Checksum
2025-11-12 00:37:52.487900 | controller | 1ff15d4bdb3e: Download complete
2025-11-12 00:37:52.487958 | controller | 171857c49d0f: Pull complete
2025-11-12 00:37:52.529260 | controller | 419640447d26: Pull complete
2025-11-12 00:37:52.573136 | controller | 61e52f862619: Pull complete
2025-11-12 00:37:52.646933 | controller | 851de0426e26: Pull complete
2025-11-12 00:37:53.148643 | controller | 5f2e4724adea: Verifying Checksum
2025-11-12 00:37:54.813658 | controller | 5f2e4724adea: Download complete
2025-11-12 00:37:54.813718 | controller | a02d9a95ccb8: Verifying Checksum
2025-11-12 00:37:54.813795 | controller | a02d9a95ccb8: Download complete
2025-11-12 00:37:55.810361 | controller | 989f4ce370cb: Verifying Checksum
2025-11-12 00:37:55.810490 | controller | 989f4ce370cb: Download complete
2025-11-12 00:37:56.817404 | controller | 0d7f52cdf9df: Download complete
2025-11-12 00:37:57.623125 | controller | c423742351d5: Verifying Checksum
2025-11-12 00:37:57.623261 | controller | c423742351d5: Download complete
2025-11-12 00:37:58.480534 | controller | 554699364730: Verifying Checksum
2025-11-12 00:37:58.480730 | controller | 554699364730: Download complete
2025-11-12 00:37:59.511393 | controller | 07a2f61c96b8: Verifying Checksum
2025-11-12 00:37:59.511635 | controller | 07a2f61c96b8: Download complete
2025-11-12 00:38:00.599617 | controller | 041f122f27ae: Verifying Checksum
2025-11-12 00:38:00.600227 | controller | 041f122f27ae: Download complete
2025-11-12 00:38:02.193412 | controller | 109236341d21: Verifying Checksum
2025-11-12 00:38:02.193626 | controller | 109236341d21: Download complete
2025-11-12 00:38:02.748007 | controller | ca6f5098b12e: Verifying Checksum
2025-11-12 00:38:02.748128 | controller | ca6f5098b12e: Download complete
2025-11-12 00:38:03.855917 | controller | 1eb3e7b2afd9: Verifying Checksum
2025-11-12 00:38:03.856333 | controller | 1eb3e7b2afd9: Download complete
2025-11-12 00:38:04.693318 | controller | 18a0b750727a: Verifying Checksum
2025-11-12 00:38:04.695235 | controller | 18a0b750727a: Download complete
2025-11-12 00:38:04.794205 | controller | 17f2f596716c: Verifying Checksum
2025-11-12 00:38:04.794503 | controller | 17f2f596716c: Download complete
2025-11-12 00:38:06.005203 | controller | fdfc10566405: Verifying Checksum
2025-11-12 00:38:06.005395 | controller | fdfc10566405: Download complete
2025-11-12 00:38:06.026749 | controller | f35a9998a9bd: Verifying Checksum
2025-11-12 00:38:06.027447 | controller | f35a9998a9bd: Download complete
2025-11-12 00:38:06.994007 | controller | 1ff1c442c5fe: Verifying Checksum
2025-11-12 00:38:06.994122 | controller | 1ff1c442c5fe: Download complete
2025-11-12 00:38:11.765995 | controller | 0edcf9fb2b15: Verifying Checksum
2025-11-12 00:38:11.766211 | controller | 0edcf9fb2b15: Download complete
2025-11-12 00:38:13.341854 | controller | a3443c4a17ed: Verifying Checksum
2025-11-12 00:38:13.342068 | controller | a3443c4a17ed: Download complete
2025-11-12 00:38:18.874473 | controller | 5af43b69e845: Verifying Checksum
2025-11-12 00:38:18.874686 | controller | 5af43b69e845: Download complete
2025-11-12 00:38:19.084626 | controller | 1651773effb2: Verifying Checksum
2025-11-12 00:38:19.084737 | controller | 1651773effb2: Download complete
2025-11-12 00:38:23.532446 | controller | 79e23c5c0fe4: Verifying Checksum
2025-11-12 00:38:25.496195 | controller | 79e23c5c0fe4: Download complete
2025-11-12 00:38:25.497258 | controller | e5058b1faaa6: Verifying Checksum
2025-11-12 00:38:26.040688 | controller | e5058b1faaa6: Download complete
2025-11-12 00:38:26.040783 | controller | 37425716ea5b: Verifying Checksum
2025-11-12 00:38:30.657004 | controller | 37425716ea5b: Download complete
2025-11-12 00:38:30.657110 | controller | 846775490569: Verifying Checksum
2025-11-12 00:38:30.657158 | controller | 846775490569: Download complete
2025-11-12 00:38:45.902750 | controller | 37425716ea5b: Pull complete
2025-11-12 00:38:45.921152 | controller | cd9b0c439616: Pull complete
2025-11-12 00:38:45.946854 | controller | d4612dbd8855: Pull complete
2025-11-12 00:38:51.404379 | controller | 554699364730: Pull complete
2025-11-12 00:38:51.426664 | controller | 1ff15d4bdb3e: Pull complete
2025-11-12 00:38:51.449669 | controller | 5f2e4724adea: Pull complete
2025-11-12 00:38:51.498722 | controller | a02d9a95ccb8: Pull complete
2025-11-12 00:38:51.523011 | controller | 989f4ce370cb: Pull complete
2025-11-12 00:38:51.545657 | controller | 0d7f52cdf9df: Pull complete
2025-11-12 00:38:51.572141 | controller | c423742351d5: Pull complete
2025-11-12 00:38:53.914571 | controller | 109236341d21: Pull complete
2025-11-12 00:38:53.946513 | controller | 07a2f61c96b8: Pull complete
2025-11-12 00:38:53.974035 | controller | 041f122f27ae: Pull complete
2025-11-12 00:38:54.143893 | controller | ca6f5098b12e: Pull complete
2025-11-12 00:38:54.199882 | controller | 1eb3e7b2afd9: Pull complete
2025-11-12 00:38:54.277862 | controller | 18a0b750727a: Pull complete
2025-11-12 00:38:54.301156 | controller | 17f2f596716c: Pull complete
2025-11-12 00:38:54.333164 | controller | f35a9998a9bd: Pull complete
2025-11-12 00:38:54.363128 | controller | fdfc10566405: Pull complete
2025-11-12 00:38:54.394102 | controller | 1ff1c442c5fe: Pull complete
2025-11-12 00:38:56.123455 | controller | a3443c4a17ed: Pull complete
2025-11-12 00:38:57.877586 | controller | 0edcf9fb2b15: Pull complete
2025-11-12 00:39:00.777651 | controller | 5af43b69e845: Pull complete
2025-11-12 00:39:03.528702 | controller | 1651773effb2: Pull complete
2025-11-12 00:39:07.319885 | controller | 79e23c5c0fe4: Pull complete
2025-11-12 00:39:10.651283 | controller | e5058b1faaa6: Pull complete
2025-11-12 00:39:13.416524 | controller | 846775490569: Pull complete
2025-11-12 00:39:13.437449 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2025-11-12 00:39:13.441172 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2025-11-12 00:39:13.449348 | controller | quay.io/ansible/default-test-container:2.9.0
2025-11-12 00:39:13.451929 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2025-11-12 00:39:13.485536 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2025-11-12 00:39:14.895010 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2025-11-12 00:39:14.895076 | controller | 04a5f4cda3ee: Pulling fs layer
2025-11-12 00:39:14.895734 | controller | ff496a88c8ed: Pulling fs layer
2025-11-12 00:39:14.895788 | controller | 0ce83f459fe7: Pulling fs layer
2025-11-12 00:39:14.895861 | controller | 2e5170e1f099: Pulling fs layer
2025-11-12 00:39:14.895913 | controller | 7641eb41b08c: Pulling fs layer
2025-11-12 00:39:14.895979 | controller | ad15fa9da398: Pulling fs layer
2025-11-12 00:39:14.896042 | controller | 087d91352424: Pulling fs layer
2025-11-12 00:39:14.896085 | controller | 8b92efd6a100: Pulling fs layer
2025-11-12 00:39:14.896138 | controller | 2e5170e1f099: Waiting
2025-11-12 00:39:14.896204 | controller | 7641eb41b08c: Waiting
2025-11-12 00:39:14.896258 | controller | ad15fa9da398: Waiting
2025-11-12 00:39:14.896309 | controller | 087d91352424: Waiting
2025-11-12 00:39:14.896369 | controller | 8b92efd6a100: Waiting
2025-11-12 00:39:15.253738 | controller | ff496a88c8ed: Verifying Checksum
2025-11-12 00:39:15.266694 | controller | ff496a88c8ed: Download complete
2025-11-12 00:39:15.266778 | controller | 0ce83f459fe7: Verifying Checksum
2025-11-12 00:39:15.551169 | controller | 0ce83f459fe7: Download complete
2025-11-12 00:39:15.551248 | controller | 04a5f4cda3ee: Verifying Checksum
2025-11-12 00:39:15.646495 | controller | 04a5f4cda3ee: Download complete
2025-11-12 00:39:15.646576 | controller | 7641eb41b08c: Verifying Checksum
2025-11-12 00:39:15.894496 | controller | 7641eb41b08c: Download complete
2025-11-12 00:39:15.894587 | controller | 2e5170e1f099: Verifying Checksum
2025-11-12 00:39:15.926639 | controller | 2e5170e1f099: Download complete
2025-11-12 00:39:15.926719 | controller | ad15fa9da398: Verifying Checksum
2025-11-12 00:39:15.927619 | controller | ad15fa9da398: Download complete
2025-11-12 00:39:16.104767 | controller | 087d91352424: Verifying Checksum
2025-11-12 00:39:16.104836 | controller | 087d91352424: Download complete
2025-11-12 00:39:16.290213 | controller | 8b92efd6a100: Verifying Checksum
2025-11-12 00:39:17.667996 | controller | 8b92efd6a100: Download complete
2025-11-12 00:39:17.668080 | controller | 04a5f4cda3ee: Pull complete
2025-11-12 00:39:17.721083 | controller | ff496a88c8ed: Pull complete
2025-11-12 00:39:17.776165 | controller | 0ce83f459fe7: Pull complete
2025-11-12 00:39:19.199072 | controller | 2e5170e1f099: Pull complete
2025-11-12 00:39:19.232563 | controller | 7641eb41b08c: Pull complete
2025-11-12 00:39:19.264095 | controller | ad15fa9da398: Pull complete
2025-11-12 00:39:20.379084 | controller | 087d91352424: Pull complete
2025-11-12 00:39:20.405648 | controller | 8b92efd6a100: Pull complete
2025-11-12 00:39:20.431305 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2025-11-12 00:39:20.435921 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2025-11-12 00:39:20.446636 | controller | quay.io/ansible/pypi-test-container:1.0.0
2025-11-12 00:39:20.451204 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2025-11-12 00:39:26.943227 | controller | Run command: docker inspect ab2a0a204cf96733d2f5cd4b0b977c04572e5f46590804003ad1275a6eb6230b
2025-11-12 00:39:27.013685 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2025-11-12 00:39:27.026911 | controller | Including collection: ansible.netcommon (292 files)
2025-11-12 00:39:27.041225 | controller | Including collection: ansible.network (23 files)
2025-11-12 00:39:27.041305 | controller | Including collection: ansible.utils (442 files)
2025-11-12 00:39:27.085356 | controller | Including collection: arista.eos (1022 files)
2025-11-12 00:39:27.139157 | controller | Including collection: cisco.ios (1172 files)
2025-11-12 00:39:27.186015 | controller | Including collection: cisco.iosxr (1043 files)
2025-11-12 00:39:27.268279 | controller | Including collection: cisco.nxos (1684 files)
2025-11-12 00:39:27.311286 | controller | Including collection: junipernetworks.junos (963 files)
2025-11-12 00:39:27.349352 | controller | Creating a payload archive containing 7367 files...
2025-11-12 00:39:29.638935 | controller | Created a 5982643 byte payload archive containing 7367 files in 2 seconds.
2025-11-12 00:39:29.645143 | controller | Assuming Docker is available on localhost.
2025-11-12 00:39:29.646005 | controller | Run command: docker run --detach --volume /sys/fs/cgroup:/sys/fs/cgroup:ro --privileged=false --security-opt seccomp=unconfined --volume /var/run/docker.sock:/var/run/docker.sock quay.io/ansible/default-test-container:2.9.0
2025-11-12 00:39:30.253516 | controller | Run command: docker exec -i c03e317b7be04d31f2eb3838c7cb46221daf60800b32ec4081c9a63512924b5e dd of=/root/docker.sh bs=65536
2025-11-12 00:39:30.348525 | controller | Run command: docker exec c03e317b7be04d31f2eb3838c7cb46221daf60800b32ec4081c9a63512924b5e /bin/bash /root/docker.sh
2025-11-12 00:39:30.427908 | controller | Run command: docker exec -i c03e317b7be04d31f2eb3838c7cb46221daf60800b32ec4081c9a63512924b5e dd of=/root/test.tgz bs=65536
2025-11-12 00:39:30.535554 | controller | Run command: docker exec c03e317b7be04d31f2eb3838c7cb46221daf60800b32ec4081c9a63512924b5e tar oxzf /root/test.tgz -C /root
2025-11-12 00:39:31.702749 | controller | Run command: docker exec c03e317b7be04d31f2eb3838c7cb46221daf60800b32ec4081c9a63512924b5e /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-543wkem1.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2025-11-12 00:39:32.173822 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2025-11-12 00:39:32.174039 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2025-11-12 00:39:32.174050 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2025-11-12 00:39:32.174061 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2025-11-12 00:39:32.181938 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2025-11-12 00:39:32.181963 | controller | No tests applicable.
2025-11-12 00:39:32.182177 | controller | Running sanity test 'ansible-doc' with Python 3.6
2025-11-12 00:39:32.183553 | controller | No tests applicable.
2025-11-12 00:39:32.183738 | controller | Running sanity test 'changelog' with Python 3.6
2025-11-12 00:39:32.184372 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/ansible-test.txt
2025-11-12 00:39:33.279848 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt
2025-11-12 00:39:33.903326 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2025-11-12 00:39:48.926935 | controller |   WARNING: Retrying (Retry(total=4, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-11-12 00:40:04.445085 | controller |   WARNING: Retrying (Retry(total=3, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-11-12 00:40:20.464137 | controller |   WARNING: Retrying (Retry(total=2, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-11-12 00:40:37.492512 | controller |   WARNING: Retrying (Retry(total=1, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-11-12 00:40:56.513505 | controller |   WARNING: Retrying (Retry(total=0, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-11-12 00:41:11.529278 | controller |   ERROR: Could not find a version that satisfies the requirement antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2)) (from versions: none)
2025-11-12 00:41:11.530387 | controller | ERROR: No matching distribution found for antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2025-11-12 00:41:11.713454 | controller | ERROR: Command "/usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt" returned exit status 1.
2025-11-12 00:41:11.792523 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2025-11-12 00:41:11.792601 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2025-11-12 00:41:11.792608 | controller | Removing custom PyPI hosts entries: /etc/hosts
2025-11-12 00:41:11.792626 | controller | Run command: docker exec c03e317b7be04d31f2eb3838c7cb46221daf60800b32ec4081c9a63512924b5e tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2025-11-12 00:41:11.894223 | controller | Run command: docker exec -i c03e317b7be04d31f2eb3838c7cb46221daf60800b32ec4081c9a63512924b5e dd if=/root/output.tgz bs=65536
2025-11-12 00:41:11.997515 | controller | Run command: tar oxzf /tmp/ansible-result-rwey1s_1.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2025-11-12 00:41:12.019926 | controller | Run command: docker rm -f ab2a0a204cf96733d2f5cd4b0b977c04572e5f46590804003ad1275a6eb6230b
2025-11-12 00:41:12.338983 | controller | Run command: docker rm -f c03e317b7be04d31f2eb3838c7cb46221daf60800b32ec4081c9a63512924b5e
2025-11-12 00:41:13.026708 | controller | ERROR: Command "docker exec c03e317b7be04d31f2eb3838c7cb46221daf60800b32ec4081c9a63512924b5e /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-543wkem1.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2025-11-12 00:41:13.504494 | controller | ERROR
2025-11-12 00:41:13.504974 | controller | {
2025-11-12 00:41:13.505073 | controller |   "delta": "0:03:27.149376",
2025-11-12 00:41:13.505124 | controller |   "end": "2025-11-12 00:41:13.083741",
2025-11-12 00:41:13.505182 | controller |   "msg": "non-zero return code",
2025-11-12 00:41:13.505238 | controller |   "rc": 1,
2025-11-12 00:41:13.505290 | controller |   "start": "2025-11-12 00:37:45.934365"
2025-11-12 00:41:13.505344 | controller | }
failure
2025-11-12 00:41:13.511830 | 
2025-11-12 00:41:13.511945 | PLAY RECAP
2025-11-12 00:41:13.512035 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2025-11-12 00:41:13.512080 | 
2025-11-12 00:41:13.673265 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-11-12 00:41:13.674473 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-12 00:41:14.386366 | 
2025-11-12 00:41:14.386504 | PLAY [all]
2025-11-12 00:41:14.408119 | 
2025-11-12 00:41:14.408211 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-11-12 00:41:17.489999 | controller | changed: non-zero return code
2025-11-12 00:41:17.504806 | 
2025-11-12 00:41:17.505004 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-11-12 00:41:17.533310 | controller | skipping: Conditional result was False
2025-11-12 00:41:17.548502 | 
2025-11-12 00:41:17.548765 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-11-12 00:41:17.593823 | 
2025-11-12 00:41:17.594186 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-11-12 00:41:17.636652 | 
2025-11-12 00:41:17.637067 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-11-12 00:41:17.664985 | controller | skipping: Conditional result was False
2025-11-12 00:41:17.674865 | 
2025-11-12 00:41:17.675111 | LOOP [fetch-subunit-output : Generate subunit file]
2025-11-12 00:41:17.711981 | 
2025-11-12 00:41:17.712269 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-11-12 00:41:17.741358 | controller | skipping: Conditional result was False
2025-11-12 00:41:17.750399 | 
2025-11-12 00:41:17.750533 | TASK [fetch-subunit-output : Remove the temporary file]
2025-11-12 00:41:17.777573 | controller | skipping: Conditional result was False
2025-11-12 00:41:17.785941 | 
2025-11-12 00:41:17.786069 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-11-12 00:41:17.812079 | controller | skipping: Conditional result was False
2025-11-12 00:41:17.854291 | 
2025-11-12 00:41:17.854440 | PLAY RECAP
2025-11-12 00:41:17.854485 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-12 00:41:17.854506 | 
2025-11-12 00:41:18.047057 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-12 00:41:18.048984 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-11-12 00:41:18.710655 | 
2025-11-12 00:41:18.710893 | PLAY [all:!appliance*]
2025-11-12 00:41:18.745818 | 
2025-11-12 00:41:18.746049 | TASK [unregister the node]
2025-11-12 00:41:19.110060 | controller | skipping: Conditional result was False
2025-11-12 00:41:19.118395 | 
2025-11-12 00:41:19.118576 | TASK [include_role : fetch-output]
2025-11-12 00:41:19.175289 | controller | ok
2025-11-12 00:41:19.214897 | 
2025-11-12 00:41:19.215053 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-12 00:41:19.272121 | controller | skipping: Conditional result was False
2025-11-12 00:41:19.278991 | 
2025-11-12 00:41:19.279074 | TASK [fetch-output : Set log path for single node]
2025-11-12 00:41:19.326736 | controller | ok
2025-11-12 00:41:19.339168 | 
2025-11-12 00:41:19.339431 | LOOP [fetch-output : Ensure local output dirs]
2025-11-12 00:41:19.800341 | controller -> localhost | ok: "/var/lib/zuul/builds/1343c62253824f3ba2006942f45f7805/work/logs"
2025-11-12 00:41:20.098575 | controller -> localhost | changed: "/var/lib/zuul/builds/1343c62253824f3ba2006942f45f7805/work/artifacts"
2025-11-12 00:41:20.395797 | controller -> localhost | changed: "/var/lib/zuul/builds/1343c62253824f3ba2006942f45f7805/work/docs"
2025-11-12 00:41:20.426298 | 
2025-11-12 00:41:20.426517 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-12 00:41:22.691790 | controller | changed:
2025-11-12 00:41:22.692241 | controller | .d..t...... ./
2025-11-12 00:41:22.692321 | controller | cd+++++++++ controller/
2025-11-12 00:41:22.692419 | controller | changed: All items complete
2025-11-12 00:41:22.692475 | 
2025-11-12 00:41:24.785748 | controller | changed: .d..t...... ./
2025-11-12 00:41:26.909033 | controller | changed: .d..t...... ./
2025-11-12 00:41:26.945626 | 
2025-11-12 00:41:26.945934 | TASK [include_role : fetch-output-openshift]
2025-11-12 00:41:26.977106 | controller | skipping: Conditional result was False
2025-11-12 00:41:26.997657 | 
2025-11-12 00:41:26.997958 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-12 00:41:27.056952 | controller | skipping: Conditional result was False
2025-11-12 00:41:27.073543 | controller | skipping: Conditional result was False
2025-11-12 00:41:27.111886 | 
2025-11-12 00:41:27.112044 | PLAY [localhost]
2025-11-12 00:41:27.125423 | 
2025-11-12 00:41:27.125526 | TASK [Run Zuul manifest role]
2025-11-12 00:41:27.152889 | localhost | ok
2025-11-12 00:41:27.183899 | 
2025-11-12 00:41:27.184067 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-12 00:41:27.603374 | localhost | changed
2025-11-12 00:41:27.610776 | 
2025-11-12 00:41:27.610915 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-12 00:41:27.643553 | localhost | ok
2025-11-12 00:41:27.656852 | 
2025-11-12 00:41:27.656969 | TASK [Set zuul-log-path fact]
2025-11-12 00:41:27.720575 | localhost | ok
2025-11-12 00:41:27.733705 | 
2025-11-12 00:41:27.733820 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-12 00:41:27.765302 | localhost | ok
2025-11-12 00:41:27.776204 | 
2025-11-12 00:41:27.776331 | LOOP [Run upload-logs-swift role]
2025-11-12 00:41:27.813163 | localhost | Output suppressed because no_log was given
2025-11-12 00:41:27.842124 | 
2025-11-12 00:41:27.842305 | TASK [Set zuul-log-path fact]
2025-11-12 00:41:27.878380 | localhost | skipping: Conditional result was False
2025-11-12 00:41:27.886209 | 
2025-11-12 00:41:27.886363 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-11-12 00:41:28.351576 | localhost -> localhost | ok: Runtime: 0:00:00.010660
2025-11-12 00:41:28.357417 | 
2025-11-12 00:41:28.357489 | TASK [upload-logs-swift : Upload logs to swift]