2026-01-25 00:13:45.303572 | Job console starting...
2026-01-25 00:13:45.314427 | Updating repositories
2026-01-25 00:13:45.479639 | Preparing job workspace
2026-01-25 00:13:49.433380 | Running Ansible setup...
2026-01-25 00:13:58.084631 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-25 00:13:58.781305 | 
2026-01-25 00:13:58.781459 | PLAY [localhost]
2026-01-25 00:13:58.790439 | 
2026-01-25 00:13:58.790518 | TASK [Gathering Facts]
2026-01-25 00:13:59.946780 | localhost | ok
2026-01-25 00:13:59.967072 | 
2026-01-25 00:13:59.967245 | TASK [Setup log path fact]
2026-01-25 00:13:59.989919 | localhost | ok
2026-01-25 00:14:00.010567 | 
2026-01-25 00:14:00.010728 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-25 00:14:00.052518 | localhost | ok
2026-01-25 00:14:00.065212 | 
2026-01-25 00:14:00.065397 | TASK [emit-job-header : Print job information]
2026-01-25 00:14:00.120907 | # Job Information
2026-01-25 00:14:00.121255 | Ansible Version: 2.15.12
2026-01-25 00:14:00.121334 | Job: ansible-test-sanity-docker-stable-2.10
2026-01-25 00:14:00.121384 | Pipeline: periodic
2026-01-25 00:14:00.121434 | Executor: ze01.softwarefactory-project.io
2026-01-25 00:14:00.121493 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-01-25 00:14:00.121545 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/5c7/ansible/5c7d82c378fd4cadb444a887b450744c/
2026-01-25 00:14:00.121588 | Event ID: 4f958dd324e442858796ac2dd4dbc41e
2026-01-25 00:14:00.132388 | 
2026-01-25 00:14:00.132539 | LOOP [emit-job-header : Print node information]
2026-01-25 00:14:00.257957 | localhost | ok:
2026-01-25 00:14:00.258171 | localhost | # Node Information
2026-01-25 00:14:00.258211 | localhost | Inventory Hostname: controller
2026-01-25 00:14:00.258246 | localhost | Hostname: np0005594715
2026-01-25 00:14:00.258284 | localhost | Username: zuul
2026-01-25 00:14:00.258329 | localhost | Distro: Fedora 37
2026-01-25 00:14:00.258361 | localhost | Provider: ansible-vexxhost-ams1
2026-01-25 00:14:00.258387 | localhost | Region: ams1
2026-01-25 00:14:00.258412 | localhost | Label: ansible-fedora-37-1vcpu
2026-01-25 00:14:00.258437 | localhost | Product Name: OpenStack Nova
2026-01-25 00:14:00.258462 | localhost | Interface IP: 38.129.16.30
2026-01-25 00:14:00.271993 | 
2026-01-25 00:14:00.272145 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-25 00:14:00.686073 | localhost -> localhost | changed
2026-01-25 00:14:00.700506 | 
2026-01-25 00:14:00.700719 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-25 00:14:01.606383 | localhost -> localhost | changed
2026-01-25 00:14:01.628209 | 
2026-01-25 00:14:01.628286 | PLAY [all:!appliance*]
2026-01-25 00:14:01.645143 | 
2026-01-25 00:14:01.645233 | TASK [include_role : start-zuul-console]
2026-01-25 00:14:01.664189 | controller | ok
2026-01-25 00:14:01.679090 | 
2026-01-25 00:14:01.679187 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-25 00:14:02.725135 | controller | ok
2026-01-25 00:14:02.743464 | 
2026-01-25 00:14:02.743604 | TASK [use-our-mirror : Retrieve the IP address]
2026-01-25 00:14:05.342711 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-01-25 00:14:05.353811 | 
2026-01-25 00:14:05.353973 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-01-25 00:14:05.737548 | controller | skipping: Conditional result was False
2026-01-25 00:14:05.752216 | 
2026-01-25 00:14:05.752371 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-01-25 00:14:05.790587 | controller | skipping: Conditional result was False
2026-01-25 00:14:05.805668 | 
2026-01-25 00:14:05.805803 | TASK [use-our-mirror : Create the podman configuration directory]
2026-01-25 00:14:05.834079 | controller | skipping: Conditional result was False
2026-01-25 00:14:05.852252 | 
2026-01-25 00:14:05.852399 | TASK [use-our-mirror : Copy the podman configuration]
2026-01-25 00:14:05.900418 | controller | skipping: Conditional result was False
2026-01-25 00:14:05.914737 | 
2026-01-25 00:14:05.914871 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-01-25 00:14:05.942224 | controller | skipping: Conditional result was False
2026-01-25 00:14:05.956239 | 
2026-01-25 00:14:05.956374 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-01-25 00:14:05.993514 | controller | skipping: Conditional result was False
2026-01-25 00:14:06.008698 | 
2026-01-25 00:14:06.008803 | TASK [Disable Fedora Modular]
2026-01-25 00:14:07.064555 | controller | changed
2026-01-25 00:14:07.078953 | 
2026-01-25 00:14:07.079181 | TASK [Enable EPEL]
2026-01-25 00:14:07.107178 | controller | skipping: Conditional result was False
2026-01-25 00:14:07.120985 | 
2026-01-25 00:14:07.121148 | TASK [Register the RHEL node]
2026-01-25 00:14:07.537396 | 
2026-01-25 00:14:07.537809 | TASK [Show the subscription-manager status]
2026-01-25 00:14:07.951736 | controller | skipping: Conditional result was False
2026-01-25 00:14:07.960282 | 
2026-01-25 00:14:07.960386 | TASK [Enable EPEL on RHEL]
2026-01-25 00:14:08.361631 | controller | skipping: Conditional result was False
2026-01-25 00:14:08.369233 | 
2026-01-25 00:14:08.369375 | TASK [Install git and tox]
2026-01-25 00:16:13.424227 | controller | changed
2026-01-25 00:16:13.437887 | 
2026-01-25 00:16:13.438125 | TASK [include_role : prepare-workspace]
2026-01-25 00:16:13.503425 | controller | ok
2026-01-25 00:16:13.589870 | 
2026-01-25 00:16:13.590031 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-25 00:16:14.454912 | controller | ok
2026-01-25 00:16:14.462975 | 
2026-01-25 00:16:14.463089 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-25 00:16:34.744966 | controller | Output suppressed because no_log was given
2026-01-25 00:16:34.760963 | 
2026-01-25 00:16:34.761112 | TASK [include_role : prepare-workspace-openshift]
2026-01-25 00:16:34.797732 | controller | skipping: Conditional result was False
2026-01-25 00:16:34.824320 | 
2026-01-25 00:16:34.824415 | PLAY [all:!appliance]
2026-01-25 00:16:34.843342 | 
2026-01-25 00:16:34.843459 | TASK [Run add-build-sshkey role (RSA)]
2026-01-25 00:16:34.865651 | controller | ok
2026-01-25 00:16:34.889078 | 
2026-01-25 00:16:34.889190 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-25 00:16:35.137766 | controller -> localhost | ok
2026-01-25 00:16:35.148455 | 
2026-01-25 00:16:35.148584 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-25 00:16:35.180294 | controller | ok
2026-01-25 00:16:35.210051 | controller | included: /var/lib/zuul/builds/5c7d82c378fd4cadb444a887b450744c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-25 00:16:35.223735 | 
2026-01-25 00:16:35.223889 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-25 00:16:35.732093 | controller -> localhost | Generating public/private rsa key pair.
2026-01-25 00:16:35.732365 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/5c7d82c378fd4cadb444a887b450744c/work/5c7d82c378fd4cadb444a887b450744c_id_rsa.
2026-01-25 00:16:35.732406 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/5c7d82c378fd4cadb444a887b450744c/work/5c7d82c378fd4cadb444a887b450744c_id_rsa.pub.
2026-01-25 00:16:35.732434 | controller -> localhost | The key fingerprint is:
2026-01-25 00:16:35.732461 | controller -> localhost | SHA256:josQtC1tUxAIcO8dPfOwUSHSpT455Z634I/gpkpA++4 zuul-build-sshkey
2026-01-25 00:16:35.732486 | controller -> localhost | The key's randomart image is:
2026-01-25 00:16:35.732512 | controller -> localhost | +---[RSA 2048]----+
2026-01-25 00:16:35.732536 | controller -> localhost | |+...o.....+.     |
2026-01-25 00:16:35.732562 | controller -> localhost | | ... . o.+       |
2026-01-25 00:16:35.732587 | controller -> localhost | |  o . o B .      |
2026-01-25 00:16:35.732613 | controller -> localhost | | o * o o @       |
2026-01-25 00:16:35.732637 | controller -> localhost | |  * * . S o      |
2026-01-25 00:16:35.732675 | controller -> localhost | |   * . o + .     |
2026-01-25 00:16:35.732706 | controller -> localhost | |  . o . o + .    |
2026-01-25 00:16:35.732732 | controller -> localhost | |   + . o.o + .   |
2026-01-25 00:16:35.732757 | controller -> localhost | |   .E.oo. o.o    |
2026-01-25 00:16:35.732785 | controller -> localhost | +----[SHA256]-----+
2026-01-25 00:16:35.732852 | controller -> localhost | ok: Runtime: 0:00:00.099097
2026-01-25 00:16:35.742487 | 
2026-01-25 00:16:35.742590 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-25 00:16:35.774456 | controller | ok
2026-01-25 00:16:35.786169 | controller | included: /var/lib/zuul/builds/5c7d82c378fd4cadb444a887b450744c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-25 00:16:35.795148 | 
2026-01-25 00:16:35.795233 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-25 00:16:35.819755 | controller | skipping: Conditional result was False
2026-01-25 00:16:35.826807 | 
2026-01-25 00:16:35.826913 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-25 00:16:36.976558 | controller | changed
2026-01-25 00:16:36.991560 | 
2026-01-25 00:16:36.991742 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-25 00:16:37.643556 | controller | ok
2026-01-25 00:16:37.651820 | 
2026-01-25 00:16:37.651906 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-25 00:16:40.748698 | controller | changed
2026-01-25 00:16:40.756033 | 
2026-01-25 00:16:40.756157 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-25 00:16:43.914495 | controller | changed
2026-01-25 00:16:43.929496 | 
2026-01-25 00:16:43.929658 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-25 00:16:43.955096 | controller | skipping: Conditional result was False
2026-01-25 00:16:43.962603 | 
2026-01-25 00:16:43.962739 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-25 00:16:44.366350 | controller -> localhost | changed
2026-01-25 00:16:44.385106 | 
2026-01-25 00:16:44.385307 | TASK [add-build-sshkey : Add back temp key]
2026-01-25 00:16:44.675040 | controller -> localhost | Identity added: /var/lib/zuul/builds/5c7d82c378fd4cadb444a887b450744c/work/5c7d82c378fd4cadb444a887b450744c_id_rsa (zuul-build-sshkey)
2026-01-25 00:16:44.675292 | controller -> localhost | ok: Runtime: 0:00:00.008448
2026-01-25 00:16:44.682028 | 
2026-01-25 00:16:44.682096 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-25 00:16:45.647620 | controller | ok
2026-01-25 00:16:45.658091 | 
2026-01-25 00:16:45.658192 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-25 00:16:45.682776 | controller | skipping: Conditional result was False
2026-01-25 00:16:45.694344 | 
2026-01-25 00:16:45.694427 | TASK [Run add-build-sshkey role (ECDSA)]
2026-01-25 00:16:45.714814 | controller | ok
2026-01-25 00:16:45.731116 | 
2026-01-25 00:16:45.731219 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-25 00:16:45.948868 | controller -> localhost | ok
2026-01-25 00:16:45.956765 | 
2026-01-25 00:16:45.956837 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-25 00:16:45.987599 | controller | ok
2026-01-25 00:16:45.999388 | controller | included: /var/lib/zuul/builds/5c7d82c378fd4cadb444a887b450744c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-25 00:16:46.005557 | 
2026-01-25 00:16:46.005637 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-25 00:16:46.311107 | controller -> localhost | Generating public/private ecdsa key pair.
2026-01-25 00:16:46.311494 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/5c7d82c378fd4cadb444a887b450744c/work/5c7d82c378fd4cadb444a887b450744c_id_ecdsa.
2026-01-25 00:16:46.311553 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/5c7d82c378fd4cadb444a887b450744c/work/5c7d82c378fd4cadb444a887b450744c_id_ecdsa.pub.
2026-01-25 00:16:46.311612 | controller -> localhost | The key fingerprint is:
2026-01-25 00:16:46.311654 | controller -> localhost | SHA256:nRe1OcTmblt6ySNdhp/aVFv31tnjNTy3EYSsvmuXhOE zuul-build-sshkey
2026-01-25 00:16:46.311694 | controller -> localhost | The key's randomart image is:
2026-01-25 00:16:46.311733 | controller -> localhost | +---[ECDSA 521]---+
2026-01-25 00:16:46.311814 | controller -> localhost | |             .o  |
2026-01-25 00:16:46.311889 | controller -> localhost | |            .o+o |
2026-01-25 00:16:46.311933 | controller -> localhost | |            .=+. |
2026-01-25 00:16:46.311972 | controller -> localhost | |         . o..o. |
2026-01-25 00:16:46.312046 | controller -> localhost | |        S +.+. o+|
2026-01-25 00:16:46.312099 | controller -> localhost | |          .E .= #|
2026-01-25 00:16:46.312139 | controller -> localhost | |           ...+/@|
2026-01-25 00:16:46.312176 | controller -> localhost | |           ..+*B@|
2026-01-25 00:16:46.312212 | controller -> localhost | |          .oo.o=.|
2026-01-25 00:16:46.312248 | controller -> localhost | +----[SHA256]-----+
2026-01-25 00:16:46.312350 | controller -> localhost | ok: Runtime: 0:00:00.010661
2026-01-25 00:16:46.329334 | 
2026-01-25 00:16:46.329527 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-25 00:16:46.370172 | controller | ok
2026-01-25 00:16:46.407038 | controller | included: /var/lib/zuul/builds/5c7d82c378fd4cadb444a887b450744c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-25 00:16:46.416064 | 
2026-01-25 00:16:46.416136 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-25 00:16:46.450647 | controller | skipping: Conditional result was False
2026-01-25 00:16:46.457619 | 
2026-01-25 00:16:46.457704 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-25 00:16:47.434660 | controller | changed
2026-01-25 00:16:47.442703 | 
2026-01-25 00:16:47.442809 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-25 00:16:48.092968 | controller | ok
2026-01-25 00:16:48.106580 | 
2026-01-25 00:16:48.106738 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-25 00:16:51.211742 | controller | changed
2026-01-25 00:16:51.224267 | 
2026-01-25 00:16:51.224401 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-25 00:16:54.315391 | controller | changed
2026-01-25 00:16:54.327264 | 
2026-01-25 00:16:54.327838 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-25 00:16:54.356217 | controller | skipping: Conditional result was False
2026-01-25 00:16:54.369526 | 
2026-01-25 00:16:54.369679 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-25 00:16:54.818132 | controller -> localhost | changed
2026-01-25 00:16:54.831668 | 
2026-01-25 00:16:54.832009 | TASK [add-build-sshkey : Add back temp key]
2026-01-25 00:16:55.204286 | controller -> localhost | Identity added: /var/lib/zuul/builds/5c7d82c378fd4cadb444a887b450744c/work/5c7d82c378fd4cadb444a887b450744c_id_ecdsa (zuul-build-sshkey)
2026-01-25 00:16:55.204545 | controller -> localhost | ok: Runtime: 0:00:00.018303
2026-01-25 00:16:55.217235 | 
2026-01-25 00:16:55.217360 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-25 00:16:56.029589 | controller | ok
2026-01-25 00:16:56.037918 | 
2026-01-25 00:16:56.037991 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-25 00:16:56.062453 | controller | skipping: Conditional result was False
2026-01-25 00:16:56.083062 | 
2026-01-25 00:16:56.083222 | TASK [include_role : remove-zuul-sshkey]
2026-01-25 00:16:56.099049 | controller | skipping: Conditional result was False
2026-01-25 00:16:56.114684 | 
2026-01-25 00:16:56.115085 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-25 00:16:56.825040 | controller | ok: "logs"
2026-01-25 00:16:56.825806 | controller | ok: All items complete
2026-01-25 00:16:56.825844 | 
2026-01-25 00:16:57.472700 | controller | ok: "artifacts"
2026-01-25 00:16:58.141545 | controller | ok: "docs"
2026-01-25 00:16:58.152921 | 
2026-01-25 00:16:58.153084 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-25 00:16:58.914121 | controller | changed: "logs"
2026-01-25 00:16:59.622512 | controller | changed: "artifacts"
2026-01-25 00:17:00.319102 | controller | changed: "docs"
2026-01-25 00:17:00.392250 | 
2026-01-25 00:17:00.392375 | PLAY RECAP
2026-01-25 00:17:00.392421 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-01-25 00:17:00.392447 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-25 00:17:00.392465 | 
2026-01-25 00:17:00.530228 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-25 00:17:00.531073 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-25 00:17:01.100268 | 
2026-01-25 00:17:01.100390 | PLAY [all]
2026-01-25 00:17:01.123524 | 
2026-01-25 00:17:01.123637 | TASK [Install binary dependencies]
2026-01-25 00:17:01.201279 | controller | ok
2026-01-25 00:17:01.233327 | 
2026-01-25 00:17:01.233494 | TASK [bindep : Include find tasks]
2026-01-25 00:17:01.272446 | controller | ok
2026-01-25 00:17:01.289648 | controller | included: /var/lib/zuul/builds/5c7d82c378fd4cadb444a887b450744c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-01-25 00:17:01.296529 | 
2026-01-25 00:17:01.296610 | TASK [bindep : Look for bindep.txt]
2026-01-25 00:17:02.269327 | controller | ok
2026-01-25 00:17:02.284140 | 
2026-01-25 00:17:02.284309 | TASK [bindep : Define bindep_file fact]
2026-01-25 00:17:02.318392 | controller | skipping: Conditional result was False
2026-01-25 00:17:02.334881 | 
2026-01-25 00:17:02.335091 | TASK [bindep : Look for other-requirements.txt]
2026-01-25 00:17:03.044650 | controller | ok
2026-01-25 00:17:03.053577 | 
2026-01-25 00:17:03.053706 | TASK [bindep : Define bindep_file fact]
2026-01-25 00:17:03.078907 | controller | skipping: Conditional result was False
2026-01-25 00:17:03.085297 | 
2026-01-25 00:17:03.085390 | TASK [bindep : Look for bindep fallback file]
2026-01-25 00:17:03.109470 | controller | skipping: Conditional result was False
2026-01-25 00:17:03.115700 | 
2026-01-25 00:17:03.115792 | TASK [bindep : Define bindep_file fact]
2026-01-25 00:17:03.140277 | controller | skipping: Conditional result was False
2026-01-25 00:17:03.148312 | 
2026-01-25 00:17:03.148395 | TASK [bindep : Include bindep tasks]
2026-01-25 00:17:03.173366 | controller | skipping: Conditional result was False
2026-01-25 00:17:03.182805 | 
2026-01-25 00:17:03.182939 | TASK [bindep : Include install tasks]
2026-01-25 00:17:03.209668 | controller | skipping: Conditional result was False
2026-01-25 00:17:03.217395 | 
2026-01-25 00:17:03.217520 | LOOP [bindep : Include package tasks]
2026-01-25 00:17:03.302442 | 
2026-01-25 00:17:03.302763 | TASK [Run test-setup role]
2026-01-25 00:17:03.329228 | controller | ok
2026-01-25 00:17:03.359182 | 
2026-01-25 00:17:03.359386 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-01-25 00:17:04.093987 | controller | ok
2026-01-25 00:17:04.107922 | 
2026-01-25 00:17:04.108111 | TASK [test-setup : Run tools/test-setup.sh]
2026-01-25 00:17:04.485222 | controller | skipping: Conditional result was False
2026-01-25 00:17:04.531769 | 
2026-01-25 00:17:04.531937 | PLAY RECAP
2026-01-25 00:17:04.531996 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-25 00:17:04.532060 | 
2026-01-25 00:17:04.699709 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-25 00:17:04.700892 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-25 00:17:05.422773 | 
2026-01-25 00:17:05.422922 | PLAY [controller]
2026-01-25 00:17:05.446636 | 
2026-01-25 00:17:05.446805 | TASK [Create the /root directory]
2026-01-25 00:17:06.618564 | controller | ok
2026-01-25 00:17:06.631739 | 
2026-01-25 00:17:06.631905 | TASK [Install glibc-langpack-en]
2026-01-25 00:17:14.879571 | controller | ok: Nothing to do
2026-01-25 00:17:14.888043 | 
2026-01-25 00:17:14.888158 | TASK [Ensure controller directory exists]
2026-01-25 00:17:15.735122 | controller | changed
2026-01-25 00:17:15.747851 | 
2026-01-25 00:17:15.747979 | TASK [Install container runtime]
2026-01-25 00:17:15.814602 | controller | ok
2026-01-25 00:17:15.878701 | 
2026-01-25 00:17:15.879416 | LOOP [ensure-docker : Gather variables for each operating system]
2026-01-25 00:17:15.922497 | controller | ok: "/var/lib/zuul/builds/5c7d82c378fd4cadb444a887b450744c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2026-01-25 00:17:15.939271 | 
2026-01-25 00:17:15.939442 | TASK [ensure-docker : Sanity check for distro selection]
2026-01-25 00:17:15.967841 | controller | skipping: Conditional result was False
2026-01-25 00:17:15.982170 | 
2026-01-25 00:17:15.982328 | TASK [ensure-docker : Sanity check for distro install]
2026-01-25 00:17:16.009660 | controller | skipping: Conditional result was False
2026-01-25 00:17:16.022543 | 
2026-01-25 00:17:16.022698 | TASK [ensure-docker : Sanity check for upstream install]
2026-01-25 00:17:16.051612 | controller | skipping: Conditional result was False
2026-01-25 00:17:16.066671 | 
2026-01-25 00:17:16.066751 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2026-01-25 00:17:16.103169 | controller | skipping: Conditional result was False
2026-01-25 00:17:16.111627 | 
2026-01-25 00:17:16.111809 | TASK [ensure-docker : Include tasks for docker install]
2026-01-25 00:17:16.148776 | controller | ok
2026-01-25 00:17:16.164435 | controller | included: /var/lib/zuul/builds/5c7d82c378fd4cadb444a887b450744c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2026-01-25 00:17:16.178425 | 
2026-01-25 00:17:16.178560 | TASK [ensure-docker : Not Implemented]
2026-01-25 00:17:16.204290 | controller | skipping: Conditional result was False
2026-01-25 00:17:16.212546 | 
2026-01-25 00:17:16.212660 | TASK [Set up docker mirrors]
2026-01-25 00:17:16.239490 | controller | ok
2026-01-25 00:17:16.270035 | 
2026-01-25 00:17:16.270211 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2026-01-25 00:17:16.306424 | controller | skipping: Conditional result was False
2026-01-25 00:17:16.316963 | 
2026-01-25 00:17:16.317118 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2026-01-25 00:17:16.414586 | controller | skipping: Conditional result was False
2026-01-25 00:17:16.465247 | 
2026-01-25 00:17:16.465424 | TASK [use-docker-mirror : Set docker_mirror fact]
2026-01-25 00:17:16.501665 | controller | skipping: Conditional result was False
2026-01-25 00:17:16.508920 | 
2026-01-25 00:17:16.508997 | TASK [use-docker-mirror : Set up docker mirrors]
2026-01-25 00:17:16.535393 | controller | skipping: Conditional result was False
2026-01-25 00:17:16.548840 | 
2026-01-25 00:17:16.549003 | TASK [Add all repositories]
2026-01-25 00:17:16.571168 | controller | ok
2026-01-25 00:17:16.599216 | 
2026-01-25 00:17:16.599395 | LOOP [ensure-package-repositories : Install repositories]
2026-01-25 00:17:16.634466 | controller | ok: "/var/lib/zuul/builds/5c7d82c378fd4cadb444a887b450744c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2026-01-25 00:17:16.634829 | controller | ok: All items complete
2026-01-25 00:17:16.634888 | 
2026-01-25 00:17:16.646629 | controller | included: /var/lib/zuul/builds/5c7d82c378fd4cadb444a887b450744c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2026-01-25 00:17:16.652972 | 
2026-01-25 00:17:16.653058 | TASK [ensure-package-repositories : Create GPG key temp directory]
2026-01-25 00:17:17.686011 | controller | changed
2026-01-25 00:17:17.700932 | 
2026-01-25 00:17:17.701154 | LOOP [ensure-package-repositories : Upload GPG keys]
2026-01-25 00:17:20.994942 | controller | changed:
2026-01-25 00:17:20.995249 | controller | {
2026-01-25 00:17:20.995316 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2026-01-25 00:17:20.995375 | controller | }
2026-01-25 00:17:21.019704 | 
2026-01-25 00:17:21.019898 | LOOP [ensure-package-repositories : Import GPG keys]
2026-01-25 00:17:22.360368 | controller | changed:
2026-01-25 00:17:22.360691 | controller | {
2026-01-25 00:17:22.360747 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2026-01-25 00:17:22.360796 | controller | }
2026-01-25 00:17:22.360858 | controller | changed: All items complete
2026-01-25 00:17:22.360901 | 
2026-01-25 00:17:22.368636 | 
2026-01-25 00:17:22.368711 | TASK [ensure-package-repositories : Remove GPG temp directory]
2026-01-25 00:17:23.082748 | controller | changed
2026-01-25 00:17:23.096828 | 
2026-01-25 00:17:23.096996 | LOOP [ensure-package-repositories : Add repositories]
2026-01-25 00:17:23.960344 | controller | changed:
2026-01-25 00:17:23.960569 | controller | {
2026-01-25 00:17:23.960606 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2026-01-25 00:17:23.960635 | controller |   "description": "Docker CE Stable - $basearch",
2026-01-25 00:17:23.960661 | controller |   "gpgcheck": true,
2026-01-25 00:17:23.960685 | controller |   "name": "docker-ce-stable"
2026-01-25 00:17:23.960709 | controller | }
2026-01-25 00:17:23.993995 | 
2026-01-25 00:17:23.994263 | TASK [ensure-docker : Install pre-reqs]
2026-01-25 00:17:29.440934 | controller | ok: Nothing to do
2026-01-25 00:17:29.453939 | 
2026-01-25 00:17:29.454076 | TASK [ensure-docker : Remove packages]
2026-01-25 00:17:32.967276 | controller | ok: Nothing to do
2026-01-25 00:17:32.978133 | 
2026-01-25 00:17:32.978294 | TASK [ensure-docker : Install upstream docker using package]
2026-01-25 00:19:02.258316 | controller | changed
2026-01-25 00:19:02.272997 | 
2026-01-25 00:19:02.273195 | TASK [ensure-docker : Setup docker]
2026-01-25 00:19:02.298809 | controller | ok
2026-01-25 00:19:02.315833 | controller | included: /var/lib/zuul/builds/5c7d82c378fd4cadb444a887b450744c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2026-01-25 00:19:02.338582 | 
2026-01-25 00:19:02.338892 | TASK [ensure-docker : Ensure "docker" group exists]
2026-01-25 00:19:03.510339 | controller | ok
2026-01-25 00:19:03.520484 | 
2026-01-25 00:19:03.520620 | TASK [ensure-docker : Add user to docker group]
2026-01-25 00:19:04.675914 | controller | changed
2026-01-25 00:19:04.692423 | 
2026-01-25 00:19:04.692655 | TASK [Update docker daemon proxy configuration]
2026-01-25 00:19:04.730302 | controller | skipping: Conditional result was False
2026-01-25 00:19:04.742203 | 
2026-01-25 00:19:04.742372 | TASK [Lower default MTU]
2026-01-25 00:19:04.758411 | controller | skipping: Conditional result was False
2026-01-25 00:19:04.771104 | 
2026-01-25 00:19:04.771265 | TASK [ensure-docker : Get the status of the docket.socket unit]
2026-01-25 00:19:05.622272 | controller | inactive
2026-01-25 00:19:06.144531 | controller | ok: Runtime: 0:00:00.016360
2026-01-25 00:19:06.158875 | 
2026-01-25 00:19:06.159113 | TASK [ensure-docker : Get the status of the docket unit]
2026-01-25 00:19:06.986922 | controller | inactive
2026-01-25 00:19:07.536715 | controller | ok: Runtime: 0:00:00.030752
2026-01-25 00:19:07.545517 | 
2026-01-25 00:19:07.545616 | TASK [ensure-docker : Restart docker]
2026-01-25 00:19:07.601680 | Trigger a Docker restart
2026-01-25 00:19:07.611815 | 
2026-01-25 00:19:07.611960 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2026-01-25 00:19:07.664585 | 
2026-01-25 00:19:07.664774 | TASK [ensure-docker : Install docker-compose]
2026-01-25 00:19:07.692237 | controller | skipping: Conditional result was False
2026-01-25 00:19:07.701662 | 
2026-01-25 00:19:07.701756 | TASK [ensure-docker : Flush handlers before role exit]
2026-01-25 00:19:07.715822 | 
2026-01-25 00:19:07.715907 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2026-01-25 00:19:11.332552 | controller | changed
2026-01-25 00:19:11.342053 | 
2026-01-25 00:19:11.342174 | TASK [ensure-docker : Assure docker service is running]
2026-01-25 00:19:16.172533 | controller | changed
2026-01-25 00:19:16.180664 | 
2026-01-25 00:19:16.180745 | TASK [ensure-docker : Assure docker.socket service is running]
2026-01-25 00:19:17.554334 | controller | ok
2026-01-25 00:19:17.565073 | 
2026-01-25 00:19:17.565268 | TASK [ensure-docker : Correct group ownership on docker sock]
2026-01-25 00:19:18.381259 | controller | ok
2026-01-25 00:19:18.394843 | 
2026-01-25 00:19:18.394989 | TASK [ensure-docker : Validate ability to talk with docker]
2026-01-25 00:19:19.065080 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2026-01-25 00:19:19.276357 | controller | ok: Runtime: 0:00:00.050802
2026-01-25 00:19:19.284702 | 
2026-01-25 00:19:19.284823 | TASK [ensure-docker : Get version details]
2026-01-25 00:19:20.004428 | controller | Client: Docker Engine - Community
2026-01-25 00:19:20.004518 | controller |  Version:           24.0.7
2026-01-25 00:19:20.004595 | controller |  API version:       1.43
2026-01-25 00:19:20.004617 | controller |  Go version:        go1.20.10
2026-01-25 00:19:20.004637 | controller |  Git commit:        afdd53b
2026-01-25 00:19:20.004645 | controller |  Built:             Thu Oct 26 09:09:39 2023
2026-01-25 00:19:20.004652 | controller |  OS/Arch:           linux/amd64
2026-01-25 00:19:20.004662 | controller |  Context:           default
2026-01-25 00:19:20.004671 | controller |
2026-01-25 00:19:20.004678 | controller | Server: Docker Engine - Community
2026-01-25 00:19:20.004684 | controller |  Engine:
2026-01-25 00:19:20.004695 | controller |   Version:          24.0.7
2026-01-25 00:19:20.004702 | controller |   API version:      1.43 (minimum version 1.12)
2026-01-25 00:19:20.004708 | controller |   Go version:       go1.20.10
2026-01-25 00:19:20.004714 | controller |   Git commit:       311b9ff
2026-01-25 00:19:20.004721 | controller |   Built:            Thu Oct 26 09:08:11 2023
2026-01-25 00:19:20.004727 | controller |   OS/Arch:          linux/amd64
2026-01-25 00:19:20.004745 | controller |   Experimental:     false
2026-01-25 00:19:20.004751 | controller |  containerd:
2026-01-25 00:19:20.004757 | controller |   Version:          1.6.25
2026-01-25 00:19:20.004764 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2026-01-25 00:19:20.004770 | controller |  runc:
2026-01-25 00:19:20.004776 | controller |   Version:          1.1.10
2026-01-25 00:19:20.004782 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2026-01-25 00:19:20.004794 | controller |  docker-init:
2026-01-25 00:19:20.007585 | controller |   Version:          0.19.0
2026-01-25 00:19:20.007603 | controller |   GitCommit:        de40ad0
2026-01-25 00:19:20.186079 | controller | ok: Runtime: 0:00:00.063052
2026-01-25 00:19:20.193989 | 
2026-01-25 00:19:20.194161 | TASK [ensure-docker : Dump installed docker details]
2026-01-25 00:19:20.237934 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version:           24.0.7\n API version:       1.43\n Go version:        go1.20.10\n Git commit:        afdd53b\n Built:             Thu Oct 26 09:09:39 2023\n OS/Arch:           linux/amd64\n Context:           default\n\nServer: Docker Engine - Community\n Engine:\n  Version:          24.0.7\n  API version:      1.43 (minimum version 1.12)\n  Go version:       go1.20.10\n  Git commit:       311b9ff\n  Built:            Thu Oct 26 09:08:11 2023\n  OS/Arch:          linux/amd64\n  Experimental:     false\n containerd:\n  Version:          1.6.25\n  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n  Version:          1.1.10\n  GitCommit:        v1.1.10-0-g18a0cb0\n docker-init:\n  Version:          0.19.0\n  GitCommit:        de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2026-01-25 00:19:19.944887', 'end': '2026-01-25 00:19:20.007939', 'delta': '0:00:00.063052', 'msg': '', 'zuul_log_id': 'fa163ec2-ffbe-b5a7-dae3-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version:           24.0.7', ' API version:       1.43', ' Go version:        go1.20.10', ' Git commit:        afdd53b', ' Built:             Thu Oct 26 09:09:39 2023', ' OS/Arch:           linux/amd64', ' Context:           default', '', 'Server: Docker Engine - Community', ' Engine:', '  Version:          24.0.7', '  API version:      1.43 (minimum version 1.12)', '  Go version:       go1.20.10', '  Git commit:       311b9ff', '  Built:            Thu Oct 26 09:08:11 2023', '  OS/Arch:          linux/amd64', '  Experimental:     false', ' containerd:', '  Version:          1.6.25', '  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', '  Version:          1.1.10', '  GitCommit:        v1.1.10-0-g18a0cb0', ' docker-init:', '  Version:          0.19.0', '  GitCommit:        de40ad0'], 'stderr_lines': [], 'failed': False}
2026-01-25 00:19:20.245127 | 
2026-01-25 00:19:20.245222 | TASK [Use buildset registry]
2026-01-25 00:19:20.272996 | controller | skipping: Conditional result was False
2026-01-25 00:19:20.285365 | 
2026-01-25 00:19:20.285456 | TASK [Ensure python3.8 is present]
2026-01-25 00:19:20.301436 | controller | skipping: Conditional result was False
2026-01-25 00:19:20.311121 | 
2026-01-25 00:19:20.311219 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-01-25 00:19:20.336647 | controller | ok
2026-01-25 00:19:20.369510 | 
2026-01-25 00:19:20.369699 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-01-25 00:19:27.526536 | controller | ok: Nothing to do
2026-01-25 00:19:27.535601 | 
2026-01-25 00:19:27.536403 | TASK [our-ensure-python : Also install python3-devel]
2026-01-25 00:19:43.541368 | controller | changed
2026-01-25 00:19:43.570121 | 
2026-01-25 00:19:43.570291 | TASK [Run ensure-virtualenv role]
2026-01-25 00:19:43.601554 | controller | ok
2026-01-25 00:19:43.655702 | 
2026-01-25 00:19:43.655863 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-01-25 00:19:44.428525 | controller | /usr/bin/virtualenv
2026-01-25 00:19:45.024312 | controller | ok: Runtime: 0:00:00.005728
2026-01-25 00:19:45.040575 | 
2026-01-25 00:19:45.040802 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-01-25 00:19:45.076276 | controller | skipping: Conditional result was False
2026-01-25 00:19:45.076555 | controller | changed: All items complete
2026-01-25 00:19:45.076584 | 
2026-01-25 00:19:45.097541 | 
2026-01-25 00:19:45.097712 | TASK [Find the full path of the Python interpreter]
2026-01-25 00:19:45.879293 | controller | /usr/bin/python3.8
2026-01-25 00:19:46.468223 | controller | ok
2026-01-25 00:19:46.479313 | 
2026-01-25 00:19:46.479524 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-01-25 00:19:48.984292 | controller | created virtual environment CPython3.8.18.final.0-64 in 1105ms
2026-01-25 00:19:49.158906 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-01-25 00:19:49.158965 | controller |   seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv)
2026-01-25 00:19:49.158981 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-01-25 00:19:49.159001 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-01-25 00:19:49.340919 | controller | changed
2026-01-25 00:19:49.353363 | 
2026-01-25 00:19:49.353537 | TASK [Set selinux package]
2026-01-25 00:19:49.393985 | controller | ok
2026-01-25 00:19:49.406196 | 
2026-01-25 00:19:49.406332 | TASK [Set selinux package (Fedora)]
2026-01-25 00:19:49.453004 | controller | ok
2026-01-25 00:19:49.460946 | 
2026-01-25 00:19:49.461082 | TASK [Install selinux into virtualenv]
2026-01-25 00:19:56.482075 | controller | Collecting selinux-please-lie-to-me
2026-01-25 00:19:56.552579 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-01-25 00:19:57.257711 | controller | Collecting setuptools<50.0.0
2026-01-25 00:19:57.267537 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-01-25 00:19:57.339364 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 12.8 MB/s eta 0:00:00
2026-01-25 00:19:57.493497 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-01-25 00:19:57.493717 | controller |   Attempting uninstall: setuptools
2026-01-25 00:19:57.495984 | controller |     Found existing installation: setuptools 62.6.0
2026-01-25 00:19:57.643920 | controller |     Uninstalling setuptools-62.6.0:
2026-01-25 00:19:57.665355 | controller |       Successfully uninstalled setuptools-62.6.0
2026-01-25 00:19:58.498923 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-01-25 00:19:58.730289 | controller |
2026-01-25 00:19:59.128737 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-01-25 00:19:59.128794 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-01-25 00:19:59.339581 | controller | ok: Runtime: 0:00:08.641223
2026-01-25 00:19:59.354280 | 
2026-01-25 00:19:59.354431 | TASK [Install pytest-forked into virtualenv]
2026-01-25 00:20:01.031621 | controller | Collecting pytest-forked
2026-01-25 00:20:01.097969 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-01-25 00:20:01.353047 | controller | Collecting pytest>=3.10
2026-01-25 00:20:01.361849 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2026-01-25 00:20:01.413633 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 8.2 MB/s eta 0:00:00
2026-01-25 00:20:01.485993 | controller | Collecting py
2026-01-25 00:20:01.495314 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-01-25 00:20:01.512940 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 7.9 MB/s eta 0:00:00
2026-01-25 00:20:01.613735 | controller | Collecting pluggy<2,>=1.5
2026-01-25 00:20:01.620674 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2026-01-25 00:20:01.666670 | controller | Collecting iniconfig
2026-01-25 00:20:01.673443 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2026-01-25 00:20:01.783181 | controller | Collecting tomli>=1
2026-01-25 00:20:01.788355 | controller |   Downloading tomli-2.4.0-py3-none-any.whl (14 kB)
2026-01-25 00:20:01.879587 | controller | Collecting packaging
2026-01-25 00:20:01.885515 | controller |   Downloading packaging-26.0-py3-none-any.whl (74 kB)
2026-01-25 00:20:01.898703 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 7.0 MB/s eta 0:00:00
2026-01-25 00:20:01.949876 | controller | Collecting exceptiongroup>=1.0.0rc8
2026-01-25 00:20:01.956800 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2026-01-25 00:20:02.049609 | controller | Collecting typing-extensions>=4.6.0
2026-01-25 00:20:02.054987 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2026-01-25 00:20:02.066212 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 4.9 MB/s eta 0:00:00
2026-01-25 00:20:02.177106 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2026-01-25 00:20:03.113524 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-26.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.4.0 typing-extensions-4.13.2
2026-01-25 00:20:03.120537 | controller |
2026-01-25 00:20:03.397145 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-01-25 00:20:03.397206 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-01-25 00:20:03.734863 | controller | ok: Runtime: 0:00:03.212232
2026-01-25 00:20:03.749143 | 
2026-01-25 00:20:03.749345 | TASK [Update pip]
2026-01-25 00:20:06.053679 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2026-01-25 00:20:06.439395 | controller | Collecting pip
2026-01-25 00:20:06.573979 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2026-01-25 00:20:06.705697 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 15.8 MB/s eta 0:00:00
2026-01-25 00:20:06.926266 | controller | Installing collected packages: pip
2026-01-25 00:20:06.927555 | controller |   Attempting uninstall: pip
2026-01-25 00:20:06.929386 | controller |     Found existing installation: pip 22.2.2
2026-01-25 00:20:07.511744 | controller |     Uninstalling pip-22.2.2:
2026-01-25 00:20:07.592708 | controller |       Successfully uninstalled pip-22.2.2
2026-01-25 00:20:10.469882 | controller | Successfully installed pip-25.0.1
2026-01-25 00:20:11.132896 | controller | ok: Runtime: 0:00:05.870809
2026-01-25 00:20:11.139824 | 
2026-01-25 00:20:11.139968 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-01-25 00:20:12.384352 | controller | changed
2026-01-25 00:20:12.392115 | 
2026-01-25 00:20:12.392207 | TASK [Install ansible into virtualenv]
2026-01-25 00:20:14.233544 | controller | Processing ./src/github.com/ansible/ansible
2026-01-25 00:20:14.235612 | controller |   Preparing metadata (setup.py): started
2026-01-25 00:20:15.060352 | controller |   Preparing metadata (setup.py): finished with status 'done'
2026-01-25 00:20:15.143052 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2026-01-25 00:20:15.160867 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2026-01-25 00:20:15.260819 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2026-01-25 00:20:15.267903 | controller |   Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.1 kB)
2026-01-25 00:20:15.770327 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2026-01-25 00:20:15.775619 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2026-01-25 00:20:15.825336 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (26.0)
2026-01-25 00:20:16.093137 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2026-01-25 00:20:16.107431 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2026-01-25 00:20:16.136965 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.8/site-packages (from cryptography->ansible-base==2.10.17.post0) (4.13.2)
2026-01-25 00:20:16.275985 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2026-01-25 00:20:16.282644 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2026-01-25 00:20:16.329685 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2026-01-25 00:20:16.335033 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2026-01-25 00:20:16.361941 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2026-01-25 00:20:16.428066 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 82.3 MB/s eta 0:00:00
2026-01-25 00:20:16.434345 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2026-01-25 00:20:16.460789 | controller | Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (806 kB)
2026-01-25 00:20:16.484495 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 32.1 MB/s eta 0:00:00
2026-01-25 00:20:16.491686 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2026-01-25 00:20:16.513027 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2026-01-25 00:20:16.529145 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2026-01-25 00:20:16.559958 | controller | Building wheels for collected packages: ansible-base
2026-01-25 00:20:16.561087 | controller |   Building wheel for ansible-base (setup.py): started
2026-01-25 00:20:18.968214 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2026-01-25 00:20:18.974346 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=4a0782b4004fc49c0eba52d638392a31f3559e039ba77de47de370c9657bf915
2026-01-25 00:20:18.974721 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-8f3lf2h3/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2026-01-25 00:20:18.998498 | controller | Successfully built ansible-base
2026-01-25 00:20:19.062575 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2026-01-25 00:20:22.039384 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-base-2.10.17.post0 cffi-1.17.1 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23
2026-01-25 00:20:22.773129 | controller | ok: Runtime: 0:00:08.860288
2026-01-25 00:20:22.781806 | 
2026-01-25 00:20:22.781893 | TASK [Setup download-artifact-fork role]
2026-01-25 00:20:22.805105 | controller | ok
2026-01-25 00:20:22.837850 | 
2026-01-25 00:20:22.838008 | TASK [download-artifact-fork : Parse inventory response]
2026-01-25 00:20:22.872989 | controller | ok
2026-01-25 00:20:22.883273 | 
2026-01-25 00:20:22.883385 | TASK [download-artifact-fork : Fetch info from Zuul API]
2026-01-25 00:20:22.920098 | controller | skipping: Conditional result was False
2026-01-25 00:20:22.930328 | 
2026-01-25 00:20:22.930459 | TASK [download-artifact-fork : Parse build response]
2026-01-25 00:20:22.966960 | controller | skipping: Conditional result was False
2026-01-25 00:20:22.976984 | 
2026-01-25 00:20:22.977161 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2026-01-25 00:20:23.807839 | controller | changed
2026-01-25 00:20:23.822216 | 
2026-01-25 00:20:23.822368 | LOOP [download-artifact-fork : Download archive by type]
2026-01-25 00:20:23.852967 | controller | skipping: Conditional result was False
2026-01-25 00:20:23.853445 | 
2026-01-25 00:20:26.299643 | controller | changed: OK (20195 bytes)
2026-01-25 00:20:26.386877 | 
2026-01-25 00:20:26.387037 | TASK [deploy-artifacts : define __collections]
2026-01-25 00:20:26.415680 | controller | ok
2026-01-25 00:20:26.421152 | 
2026-01-25 00:20:26.421227 | LOOP [deploy-artifacts : Create list of collections]
2026-01-25 00:20:26.450198 | controller | skipping: Conditional result was False
2026-01-25 00:20:26.456665 | controller | ok:
2026-01-25 00:20:26.456776 | controller | {
2026-01-25 00:20:26.456802 | controller |   "branch": "main",
2026-01-25 00:20:26.456821 | controller |   "job": "build-ansible-collection",
2026-01-25 00:20:26.456838 | controller |   "metadata": {
2026-01-25 00:20:26.456854 | controller |     "type": "ansible_collection",
2026-01-25 00:20:26.456869 | controller |     "version": "5.0.0"
2026-01-25 00:20:26.456885 | controller |   },
2026-01-25 00:20:26.456900 | controller |   "name": "ansible.network",
2026-01-25 00:20:26.456915 | controller |   "project": "ansible-collections/ansible.network",
2026-01-25 00:20:26.456930 | controller |   "url": "https://cffb88307160cbbeb3c7-e1535f9bf1e99ea2da0d186ea78719f1.ssl.cf5.rackcdn.com/ansible/9d237e9436314085a8632f85594df171/artifacts/ansible-network-5.0.0.tar.gz"
2026-01-25 00:20:26.456952 | controller | }
2026-01-25 00:20:26.472789 | 
2026-01-25 00:20:26.472907 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2026-01-25 00:20:27.914968 | controller | Starting galaxy collection install process
2026-01-25 00:20:27.915519 | controller | Process install dependency map
2026-01-25 00:20:41.147307 | controller | Starting collection install process
2026-01-25 00:20:41.147485 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2026-01-25 00:20:41.161173 | controller | ansible.network (5.0.0) was installed successfully
2026-01-25 00:20:41.161326 | controller | Installing 'ansible.netcommon:8.2.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-01-25 00:20:41.161533 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-8.2.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4164usg0jbmb/tmpz1nzi4_4
2026-01-25 00:20:42.857024 | controller | ansible.netcommon (8.2.1) was installed successfully
2026-01-25 00:20:42.857174 | controller | Installing 'ansible.utils:6.0.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-01-25 00:20:42.857458 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-utils-6.0.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4164usg0jbmb/tmpz1nzi4_4
2026-01-25 00:20:44.729615 | controller | ansible.utils (6.0.1) was installed successfully
2026-01-25 00:20:44.729762 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2026-01-25 00:20:44.729965 | 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-4164usg0jbmb/tmpz1nzi4_4
2026-01-25 00:20:47.298820 | controller | arista.eos (12.0.0) was installed successfully
2026-01-25 00:20:47.299340 | controller | Installing 'cisco.ios:11.2.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2026-01-25 00:20:47.299363 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.2.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4164usg0jbmb/tmpz1nzi4_4
2026-01-25 00:20:50.269422 | controller | cisco.ios (11.2.0) was installed successfully
2026-01-25 00:20:50.270057 | controller | Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2026-01-25 00:20:50.270080 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4164usg0jbmb/tmpz1nzi4_4
2026-01-25 00:20:53.529073 | controller | cisco.iosxr (12.1.1) was installed successfully
2026-01-25 00:20:53.529345 | controller | Installing 'cisco.nxos:11.1.2' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2026-01-25 00:20:53.529361 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.2.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4164usg0jbmb/tmpz1nzi4_4
2026-01-25 00:20:57.526546 | controller | cisco.nxos (11.1.2) was installed successfully
2026-01-25 00:20:57.526675 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2026-01-25 00:20:57.526971 | 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-4164usg0jbmb/tmpz1nzi4_4
2026-01-25 00:21:00.145437 | controller | junipernetworks.junos (11.0.0) was installed successfully
2026-01-25 00:21:00.848316 | controller | ok: Runtime: 0:00:33.030092
2026-01-25 00:21:00.865458 | 
2026-01-25 00:21:00.865645 | TASK [podman may need iptables kernel modules]
2026-01-25 00:21:02.236363 | controller | ok: Runtime: 0:00:00.006927
2026-01-25 00:21:02.293365 | 
2026-01-25 00:21:02.293531 | PLAY RECAP
2026-01-25 00:21:02.293784 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2026-01-25 00:21:02.293851 | 
2026-01-25 00:21:02.491931 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-25 00:21:02.493952 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-01-25 00:21:03.251609 | 
2026-01-25 00:21:03.251773 | PLAY [controller]
2026-01-25 00:21:03.277005 | 
2026-01-25 00:21:03.277326 | TASK [Copy the galaxy.yml on the controller]
2026-01-25 00:21:07.010867 | controller | changed
2026-01-25 00:21:07.033852 | 
2026-01-25 00:21:07.034008 | TASK [Load information from galaxy.yml]
2026-01-25 00:21:07.097550 | controller | ok
2026-01-25 00:21:07.104276 | 
2026-01-25 00:21:07.104360 | LOOP [Enable FIPS mode]
2026-01-25 00:21:07.127881 | 
2026-01-25 00:21:07.128072 | LOOP [ansible-test : Include branch specific variables.]
2026-01-25 00:21:07.188450 | controller | ok: "/var/lib/zuul/builds/5c7d82c378fd4cadb444a887b450744c/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2026-01-25 00:21:07.188700 | 
2026-01-25 00:21:07.205657 | 
2026-01-25 00:21:07.205795 | TASK [ansible-test : Setup --skip-tags for test_options]
2026-01-25 00:21:07.254007 | controller | ok
2026-01-25 00:21:07.262387 | 
2026-01-25 00:21:07.262485 | TASK [ansible-test : Enable --inventory for network-integration]
2026-01-25 00:21:07.289742 | controller | skipping: Conditional result was False
2026-01-25 00:21:07.299952 | 
2026-01-25 00:21:07.300138 | TASK [ansible-test : Enable --retry-on-error]
2026-01-25 00:21:07.316785 | controller | skipping: Conditional result was False
2026-01-25 00:21:07.325575 | 
2026-01-25 00:21:07.325687 | TASK [ansible-test : Enable --continue-on-error]
2026-01-25 00:21:07.358236 | controller | ok
2026-01-25 00:21:07.363828 | 
2026-01-25 00:21:07.363895 | TASK [ansible-test : Enable --requirements]
2026-01-25 00:21:07.378503 | controller | skipping: Conditional result was False
2026-01-25 00:21:07.385449 | 
2026-01-25 00:21:07.385566 | TASK [ansible-test : Adjust options for unit tests]
2026-01-25 00:21:07.400282 | controller | skipping: Conditional result was False
2026-01-25 00:21:07.408176 | 
2026-01-25 00:21:07.408323 | TASK [ansible-test : Install requirements with unit tests]
2026-01-25 00:21:07.440698 | controller | ok
2026-01-25 00:21:07.475163 | 
2026-01-25 00:21:07.475290 | TASK [ansible-test : Set --skip-test]
2026-01-25 00:21:07.530934 | controller | skipping: Conditional result was False
2026-01-25 00:21:07.540121 | 
2026-01-25 00:21:07.540261 | TASK [ansible-test : Reduce verbosity]
2026-01-25 00:21:07.574142 | controller | ok
2026-01-25 00:21:07.582287 | 
2026-01-25 00:21:07.582439 | TASK [ansible-test : Setup --python option]
2026-01-25 00:21:07.609100 | controller | skipping: Conditional result was False
2026-01-25 00:21:07.618230 | 
2026-01-25 00:21:07.618416 | TASK [ansible-test : Setup --docker option]
2026-01-25 00:21:07.653259 | controller | ok
2026-01-25 00:21:07.662976 | 
2026-01-25 00:21:07.663169 | TASK [ansible-test : check if MANIFEST.json exists]
2026-01-25 00:21:08.856261 | controller | ok
2026-01-25 00:21:08.869227 | 
2026-01-25 00:21:08.869374 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2026-01-25 00:21:08.897232 | controller | skipping: Conditional result was False
2026-01-25 00:21:08.904730 | 
2026-01-25 00:21:08.904836 | TASK [ansible-test : Load information from MANIFEST.json]
2026-01-25 00:21:08.930855 | controller | skipping: Conditional result was False
2026-01-25 00:21:08.938291 | 
2026-01-25 00:21:08.938382 | TASK [ansible-test : Setup location of project for integration tests]
2026-01-25 00:21:08.953188 | controller | skipping: Conditional result was False
2026-01-25 00:21:08.965417 | 
2026-01-25 00:21:08.965587 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2026-01-25 00:21:10.297121 | controller | ok
2026-01-25 00:21:10.305330 | 
2026-01-25 00:21:10.305422 | TASK [ansible-test : Load information from galaxy.yml]
2026-01-25 00:21:10.347526 | controller | ok
2026-01-25 00:21:10.361815 | 
2026-01-25 00:21:10.361951 | TASK [ansible-test : Setup location of project for integration tests]
2026-01-25 00:21:10.416662 | controller | ok
2026-01-25 00:21:10.424523 | 
2026-01-25 00:21:10.424616 | TASK [ansible-test : Setup minimum test requirements]
2026-01-25 00:21:10.472493 | controller | ok
2026-01-25 00:21:10.487121 | 
2026-01-25 00:21:10.487302 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2026-01-25 00:21:11.410245 | controller | ok
2026-01-25 00:21:11.424517 | 
2026-01-25 00:21:11.424656 | TASK [ansible-test : Install python requirements]
2026-01-25 00:21:12.888769 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-01-25 00:21:12.904705 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2026-01-25 00:21:12.944226 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-01-25 00:21:12.948963 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2026-01-25 00:21:12.960367 | controller | Requirement already satisfied: pyyaml in ./venv/lib64/python3.8/site-packages (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1)) (6.0.3)
2026-01-25 00:21:12.969000 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2026-01-25 00:21:12.986941 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2026-01-25 00:21:13.039983 | controller | Installing collected packages: pathspec, yamllint
2026-01-25 00:21:13.134041 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2026-01-25 00:21:13.797265 | controller | ok: Runtime: 0:00:01.013787
2026-01-25 00:21:13.807779 | 
2026-01-25 00:21:13.807952 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2026-01-25 00:21:14.603029 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2026-01-25 00:21:15.173082 | controller | ERROR
2026-01-25 00:21:15.173296 | controller | {
2026-01-25 00:21:15.173326 | controller |   "delta": "0:00:00.007738",
2026-01-25 00:21:15.173345 | controller |   "end": "2026-01-25 00:21:14.603285",
2026-01-25 00:21:15.173363 | controller |   "msg": "non-zero return code",
2026-01-25 00:21:15.173379 | controller |   "rc": 1,
2026-01-25 00:21:15.173395 | controller |   "start": "2026-01-25 00:21:14.595547"
2026-01-25 00:21:15.173410 | controller | }
2026-01-25 00:21:15.173434 | controller | ERROR: Ignoring Errors
2026-01-25 00:21:15.180597 | 
2026-01-25 00:21:15.180669 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2026-01-25 00:21:15.553229 | controller | skipping: Conditional result was False
2026-01-25 00:21:15.562396 | 
2026-01-25 00:21:15.562538 | TASK [ansible-test : Enable persistent connection logging]
2026-01-25 00:21:16.983175 | controller | changed: section and option added
2026-01-25 00:21:16.991191 | 
2026-01-25 00:21:16.991309 | TASK [ansible-test : Set persistent ssh_type value]
2026-01-25 00:21:18.175683 | controller | changed: option added
2026-01-25 00:21:18.184366 | 
2026-01-25 00:21:18.184455 | TASK [ansible-test : Set ansible log path]
2026-01-25 00:21:19.528512 | controller | changed: section and option added
2026-01-25 00:21:19.545314 | 
2026-01-25 00:21:19.545528 | TASK [ansible-test : Identify the changed targets]
2026-01-25 00:21:19.917472 | controller | skipping: Conditional result was False
2026-01-25 00:21:19.927951 | 
2026-01-25 00:21:19.928100 | TASK [ansible-test : set_fact]
2026-01-25 00:21:19.993521 | controller | skipping: Conditional result was False
2026-01-25 00:21:20.007924 | 
2026-01-25 00:21:20.008115 | TASK [ansible-test : Set the targets]
2026-01-25 00:21:20.060917 | controller | ok
2026-01-25 00:21:20.121567 | 
2026-01-25 00:21:20.121739 | TASK [ansible-test : set_fact]
2026-01-25 00:21:20.137523 | controller | skipping: Conditional result was False
2026-01-25 00:21:20.146189 | 
2026-01-25 00:21:20.146344 | TASK [ansible-test : List the targets]
2026-01-25 00:21:20.516225 | controller | skipping: Conditional result was False
2026-01-25 00:21:20.528656 | 
2026-01-25 00:21:20.528809 | TASK [ansible-test : set_fact]
2026-01-25 00:21:20.544691 | controller | skipping: Conditional result was False
2026-01-25 00:21:20.557119 | 
2026-01-25 00:21:20.557265 | TASK [ansible-test : set_fact]
2026-01-25 00:21:20.573482 | controller | skipping: Conditional result was False
2026-01-25 00:21:20.588187 | 
2026-01-25 00:21:20.588356 | LOOP [ansible-test : set_fact]
2026-01-25 00:21:20.649173 | 
2026-01-25 00:21:20.649414 | TASK [ansible-test : set_fact]
2026-01-25 00:21:20.665062 | controller | skipping: Conditional result was False
2026-01-25 00:21:20.676256 | 
2026-01-25 00:21:20.676417 | TASK [ansible-test : debug]
2026-01-25 00:21:20.692124 | controller | skipping: Conditional result was False
2026-01-25 00:21:20.702539 | 
2026-01-25 00:21:20.702689 | TASK [ansible-test : debug]
2026-01-25 00:21:20.735905 | About to run: ansible-test sanity --requirements --docker -v
2026-01-25 00:21:20.741567 | 
2026-01-25 00:21:20.741632 | TASK [ansible-test : Run the test suite]
2026-01-25 00:21:22.784127 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2026-01-25 00:21:22.847830 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2026-01-25 00:21:24.516210 | controller | 2.9.0: Pulling from ansible/default-test-container
2026-01-25 00:21:24.519051 | controller | 171857c49d0f: Pulling fs layer
2026-01-25 00:21:24.519182 | controller | 419640447d26: Pulling fs layer
2026-01-25 00:21:24.519196 | controller | 61e52f862619: Pulling fs layer
2026-01-25 00:21:24.519203 | controller | 851de0426e26: Pulling fs layer
2026-01-25 00:21:24.519214 | controller | 37425716ea5b: Pulling fs layer
2026-01-25 00:21:24.519223 | controller | cd9b0c439616: Pulling fs layer
2026-01-25 00:21:24.519229 | controller | d4612dbd8855: Pulling fs layer
2026-01-25 00:21:24.519255 | controller | 554699364730: Pulling fs layer
2026-01-25 00:21:24.519261 | controller | 1ff15d4bdb3e: Pulling fs layer
2026-01-25 00:21:24.519267 | controller | 5f2e4724adea: Pulling fs layer
2026-01-25 00:21:24.519274 | controller | a02d9a95ccb8: Pulling fs layer
2026-01-25 00:21:24.519281 | controller | 989f4ce370cb: Pulling fs layer
2026-01-25 00:21:24.519287 | controller | 0d7f52cdf9df: Pulling fs layer
2026-01-25 00:21:24.519293 | controller | c423742351d5: Pulling fs layer
2026-01-25 00:21:24.519299 | controller | 109236341d21: Pulling fs layer
2026-01-25 00:21:24.519305 | controller | 07a2f61c96b8: Pulling fs layer
2026-01-25 00:21:24.519311 | controller | 041f122f27ae: Pulling fs layer
2026-01-25 00:21:24.519317 | controller | ca6f5098b12e: Pulling fs layer
2026-01-25 00:21:24.519323 | controller | 1eb3e7b2afd9: Pulling fs layer
2026-01-25 00:21:24.519328 | controller | 18a0b750727a: Pulling fs layer
2026-01-25 00:21:24.519334 | controller | 17f2f596716c: Pulling fs layer
2026-01-25 00:21:24.519342 | controller | f35a9998a9bd: Pulling fs layer
2026-01-25 00:21:24.519348 | controller | fdfc10566405: Pulling fs layer
2026-01-25 00:21:24.519354 | controller | 1ff1c442c5fe: Pulling fs layer
2026-01-25 00:21:24.519360 | controller | a3443c4a17ed: Pulling fs layer
2026-01-25 00:21:24.519366 | controller | 0edcf9fb2b15: Pulling fs layer
2026-01-25 00:21:24.519371 | controller | 5af43b69e845: Pulling fs layer
2026-01-25 00:21:24.519377 | controller | 1651773effb2: Pulling fs layer
2026-01-25 00:21:24.519383 | controller | 79e23c5c0fe4: Pulling fs layer
2026-01-25 00:21:24.519392 | controller | e5058b1faaa6: Pulling fs layer
2026-01-25 00:21:24.519398 | controller | 846775490569: Pulling fs layer
2026-01-25 00:21:24.519404 | controller | 1ff15d4bdb3e: Waiting
2026-01-25 00:21:24.519410 | controller | 5f2e4724adea: Waiting
2026-01-25 00:21:24.519416 | controller | a02d9a95ccb8: Waiting
2026-01-25 00:21:24.519421 | controller | 989f4ce370cb: Waiting
2026-01-25 00:21:24.519427 | controller | 0d7f52cdf9df: Waiting
2026-01-25 00:21:24.519433 | controller | c423742351d5: Waiting
2026-01-25 00:21:24.519441 | controller | 851de0426e26: Waiting
2026-01-25 00:21:24.519447 | controller | 37425716ea5b: Waiting
2026-01-25 00:21:24.519453 | controller | cd9b0c439616: Waiting
2026-01-25 00:21:24.519459 | controller | d4612dbd8855: Waiting
2026-01-25 00:21:24.519464 | controller | 554699364730: Waiting
2026-01-25 00:21:24.519474 | controller | 07a2f61c96b8: Waiting
2026-01-25 00:21:24.519674 | controller | 041f122f27ae: Waiting
2026-01-25 00:21:24.928331 | controller | ca6f5098b12e: Waiting
2026-01-25 00:21:24.928388 | controller | 1eb3e7b2afd9: Waiting
2026-01-25 00:21:24.928396 | controller | 18a0b750727a: Waiting
2026-01-25 00:21:24.928402 | controller | 17f2f596716c: Waiting
2026-01-25 00:21:24.928408 | controller | f35a9998a9bd: Waiting
2026-01-25 00:21:24.928420 | controller | fdfc10566405: Waiting
2026-01-25 00:21:24.928426 | controller | 1ff1c442c5fe: Waiting
2026-01-25 00:21:24.928432 | controller | a3443c4a17ed: Waiting
2026-01-25 00:21:24.928438 | controller | 0edcf9fb2b15: Waiting
2026-01-25 00:21:24.928444 | controller | 5af43b69e845: Waiting
2026-01-25 00:21:24.928450 | controller | 1651773effb2: Waiting
2026-01-25 00:21:24.928456 | controller | 79e23c5c0fe4: Waiting
2026-01-25 00:21:24.928463 | controller | e5058b1faaa6: Waiting
2026-01-25 00:21:24.928469 | controller | 846775490569: Waiting
2026-01-25 00:21:24.928485 | controller | 61e52f862619: Verifying Checksum
2026-01-25 00:21:24.940903 | controller | 61e52f862619: Download complete
2026-01-25 00:21:24.940962 | controller | 419640447d26: Verifying Checksum
2026-01-25 00:21:25.277367 | controller | 419640447d26: Download complete
2026-01-25 00:21:25.277435 | controller | 171857c49d0f: Verifying Checksum
2026-01-25 00:21:25.779141 | controller | 171857c49d0f: Download complete
2026-01-25 00:21:25.780326 | controller | 851de0426e26: Verifying Checksum
2026-01-25 00:21:25.780643 | controller | 851de0426e26: Download complete
2026-01-25 00:21:26.055940 | controller | cd9b0c439616: Verifying Checksum
2026-01-25 00:21:26.686126 | controller | cd9b0c439616: Download complete
2026-01-25 00:21:26.686198 | controller | d4612dbd8855: Verifying Checksum
2026-01-25 00:21:27.529530 | controller | d4612dbd8855: Download complete
2026-01-25 00:21:27.529584 | controller | 1ff15d4bdb3e: Verifying Checksum
2026-01-25 00:21:28.437008 | controller | 1ff15d4bdb3e: Download complete
2026-01-25 00:21:28.437074 | controller | 5f2e4724adea: Verifying Checksum
2026-01-25 00:21:29.018395 | controller | 5f2e4724adea: Download complete
2026-01-25 00:21:29.018448 | controller | 171857c49d0f: Pull complete
2026-01-25 00:21:29.051474 | controller | 419640447d26: Pull complete
2026-01-25 00:21:29.072922 | controller | 61e52f862619: Pull complete
2026-01-25 00:21:29.096766 | controller | 851de0426e26: Pull complete
2026-01-25 00:21:29.838377 | controller | a02d9a95ccb8: Verifying Checksum
2026-01-25 00:21:30.577890 | controller | a02d9a95ccb8: Download complete
2026-01-25 00:21:30.577974 | controller | 989f4ce370cb: Verifying Checksum
2026-01-25 00:21:30.578181 | controller | 989f4ce370cb: Download complete
2026-01-25 00:21:31.345337 | controller | 0d7f52cdf9df: Download complete
2026-01-25 00:21:32.062816 | controller | c423742351d5: Verifying Checksum
2026-01-25 00:21:32.062965 | controller | c423742351d5: Download complete
2026-01-25 00:21:34.936433 | controller | 554699364730: Verifying Checksum
2026-01-25 00:21:34.936527 | controller | 554699364730: Download complete
2026-01-25 00:21:35.742081 | controller | 07a2f61c96b8: Verifying Checksum
2026-01-25 00:21:35.742229 | controller | 07a2f61c96b8: Download complete
2026-01-25 00:21:36.522815 | controller | 041f122f27ae: Download complete
2026-01-25 00:21:38.108321 | controller | ca6f5098b12e: Download complete
2026-01-25 00:21:39.442980 | controller | 1eb3e7b2afd9: Verifying Checksum
2026-01-25 00:21:39.443082 | controller | 1eb3e7b2afd9: Download complete
2026-01-25 00:21:40.036015 | controller | 109236341d21: Verifying Checksum
2026-01-25 00:21:40.036104 | controller | 109236341d21: Download complete
2026-01-25 00:21:40.892640 | controller | 17f2f596716c: Verifying Checksum
2026-01-25 00:21:40.892734 | controller | 17f2f596716c: Download complete
2026-01-25 00:21:40.965255 | controller | 18a0b750727a: Verifying Checksum
2026-01-25 00:21:40.965413 | controller | 18a0b750727a: Download complete
2026-01-25 00:21:41.631605 | controller | f35a9998a9bd: Verifying Checksum
2026-01-25 00:21:41.631696 | controller | f35a9998a9bd: Download complete
2026-01-25 00:21:41.836278 | controller | fdfc10566405: Verifying Checksum
2026-01-25 00:21:41.836435 | controller | fdfc10566405: Download complete
2026-01-25 00:21:42.372093 | controller | 1ff1c442c5fe: Verifying Checksum
2026-01-25 00:21:42.372269 | controller | 1ff1c442c5fe: Download complete
2026-01-25 00:21:48.901271 | controller | 0edcf9fb2b15: Verifying Checksum
2026-01-25 00:21:48.901446 | controller | 0edcf9fb2b15: Download complete
2026-01-25 00:21:50.300993 | controller | a3443c4a17ed: Verifying Checksum
2026-01-25 00:21:50.301120 | controller | a3443c4a17ed: Download complete
2026-01-25 00:21:55.265414 | controller | 1651773effb2: Verifying Checksum
2026-01-25 00:21:55.265512 | controller | 1651773effb2: Download complete
2026-01-25 00:21:57.524746 | controller | 5af43b69e845: Verifying Checksum
2026-01-25 00:21:57.524868 | controller | 5af43b69e845: Download complete
2026-01-25 00:21:59.241633 | controller | 37425716ea5b: Verifying Checksum
2026-01-25 00:21:59.242075 | controller | 37425716ea5b: Download complete
2026-01-25 00:22:00.108981 | controller | 79e23c5c0fe4: Verifying Checksum
2026-01-25 00:22:00.109056 | controller | 79e23c5c0fe4: Download complete
2026-01-25 00:22:00.832223 | controller | e5058b1faaa6: Verifying Checksum
2026-01-25 00:22:00.832323 | controller | e5058b1faaa6: Download complete
2026-01-25 00:22:08.205528 | controller | 846775490569: Verifying Checksum
2026-01-25 00:22:08.205779 | controller | 846775490569: Download complete
2026-01-25 00:22:17.779051 | controller | 37425716ea5b: Pull complete
2026-01-25 00:22:17.807010 | controller | cd9b0c439616: Pull complete
2026-01-25 00:22:17.843834 | controller | d4612dbd8855: Pull complete
2026-01-25 00:22:22.436471 | controller | 554699364730: Pull complete
2026-01-25 00:22:22.464294 | controller | 1ff15d4bdb3e: Pull complete
2026-01-25 00:22:22.492947 | controller | 5f2e4724adea: Pull complete
2026-01-25 00:22:22.544779 | controller | a02d9a95ccb8: Pull complete
2026-01-25 00:22:22.576335 | controller | 989f4ce370cb: Pull complete
2026-01-25 00:22:22.605917 | controller | 0d7f52cdf9df: Pull complete
2026-01-25 00:22:22.628822 | controller | c423742351d5: Pull complete
2026-01-25 00:22:24.965961 | controller | 109236341d21: Pull complete
2026-01-25 00:22:24.990750 | controller | 07a2f61c96b8: Pull complete
2026-01-25 00:22:25.022778 | controller | 041f122f27ae: Pull complete
2026-01-25 00:22:25.167870 | controller | ca6f5098b12e: Pull complete
2026-01-25 00:22:25.223072 | controller | 1eb3e7b2afd9: Pull complete
2026-01-25 00:22:25.264482 | controller | 18a0b750727a: Pull complete
2026-01-25 00:22:25.285558 | controller | 17f2f596716c: Pull complete
2026-01-25 00:22:25.320981 | controller | f35a9998a9bd: Pull complete
2026-01-25 00:22:25.349925 | controller | fdfc10566405: Pull complete
2026-01-25 00:22:25.376499 | controller | 1ff1c442c5fe: Pull complete
2026-01-25 00:22:27.104395 | controller | a3443c4a17ed: Pull complete
2026-01-25 00:22:28.992956 | controller | 0edcf9fb2b15: Pull complete
2026-01-25 00:22:31.891150 | controller | 5af43b69e845: Pull complete
2026-01-25 00:22:36.640063 | controller | 1651773effb2: Pull complete
2026-01-25 00:22:39.174438 | controller | 79e23c5c0fe4: Pull complete
2026-01-25 00:22:41.123328 | controller | e5058b1faaa6: Pull complete
2026-01-25 00:22:43.724504 | controller | 846775490569: Pull complete
2026-01-25 00:22:43.750542 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2026-01-25 00:22:43.759787 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2026-01-25 00:22:43.768673 | controller | quay.io/ansible/default-test-container:2.9.0
2026-01-25 00:22:43.772421 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2026-01-25 00:22:43.799182 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2026-01-25 00:22:45.315001 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2026-01-25 00:22:45.315915 | controller | 04a5f4cda3ee: Pulling fs layer
2026-01-25 00:22:45.315971 | controller | ff496a88c8ed: Pulling fs layer
2026-01-25 00:22:45.316012 | controller | 0ce83f459fe7: Pulling fs layer
2026-01-25 00:22:45.316052 | controller | 2e5170e1f099: Pulling fs layer
2026-01-25 00:22:45.316121 | controller | 7641eb41b08c: Pulling fs layer
2026-01-25 00:22:45.316161 | controller | ad15fa9da398: Pulling fs layer
2026-01-25 00:22:45.316199 | controller | 087d91352424: Pulling fs layer
2026-01-25 00:22:45.316247 | controller | 8b92efd6a100: Pulling fs layer
2026-01-25 00:22:45.316290 | controller | 2e5170e1f099: Waiting
2026-01-25 00:22:45.316329 | controller | 7641eb41b08c: Waiting
2026-01-25 00:22:45.316365 | controller | ad15fa9da398: Waiting
2026-01-25 00:22:45.316399 | controller | 087d91352424: Waiting
2026-01-25 00:22:45.316431 | controller | 8b92efd6a100: Waiting
2026-01-25 00:22:46.101876 | controller | 0ce83f459fe7: Verifying Checksum
2026-01-25 00:22:46.151004 | controller | 0ce83f459fe7: Download complete
2026-01-25 00:22:46.151061 | controller | ff496a88c8ed: Verifying Checksum
2026-01-25 00:22:46.151999 | controller | ff496a88c8ed: Download complete
2026-01-25 00:22:46.930490 | controller | 7641eb41b08c: Verifying Checksum
2026-01-25 00:22:47.746359 | controller | 7641eb41b08c: Download complete
2026-01-25 00:22:47.746434 | controller | ad15fa9da398: Verifying Checksum
2026-01-25 00:22:47.749025 | controller | ad15fa9da398: Download complete
2026-01-25 00:22:49.212884 | controller | 04a5f4cda3ee: Verifying Checksum
2026-01-25 00:22:49.213042 | controller | 04a5f4cda3ee: Download complete
2026-01-25 00:22:49.286619 | controller | 087d91352424: Verifying Checksum
2026-01-25 00:22:49.289693 | controller | 087d91352424: Download complete
2026-01-25 00:22:50.056724 | controller | 8b92efd6a100: Verifying Checksum
2026-01-25 00:22:50.056885 | controller | 8b92efd6a100: Download complete
2026-01-25 00:22:51.493607 | controller | 04a5f4cda3ee: Pull complete
2026-01-25 00:22:51.555233 | controller | ff496a88c8ed: Pull complete
2026-01-25 00:22:51.590145 | controller | 0ce83f459fe7: Pull complete
2026-01-25 00:22:52.332184 | controller | 2e5170e1f099: Verifying Checksum
2026-01-25 00:22:53.911761 | controller | 2e5170e1f099: Download complete
2026-01-25 00:22:53.911842 | controller | 2e5170e1f099: Pull complete
2026-01-25 00:22:53.943897 | controller | 7641eb41b08c: Pull complete
2026-01-25 00:22:53.976931 | controller | ad15fa9da398: Pull complete
2026-01-25 00:22:55.026003 | controller | 087d91352424: Pull complete
2026-01-25 00:22:55.062631 | controller | 8b92efd6a100: Pull complete
2026-01-25 00:22:55.090452 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2026-01-25 00:22:55.094631 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2026-01-25 00:22:55.101404 | controller | quay.io/ansible/pypi-test-container:1.0.0
2026-01-25 00:22:55.107644 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2026-01-25 00:23:03.778507 | controller | Run command: docker inspect 9296fcbe8d7b33abc0ccb6a19df4f6aed392afe423d195a2c653e5352c4678ee
2026-01-25 00:23:03.845078 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2026-01-25 00:23:03.858087 | controller | Including collection: ansible.netcommon (287 files)
2026-01-25 00:23:03.870336 | controller | Including collection: ansible.network (23 files)
2026-01-25 00:23:03.870392 | controller | Including collection: ansible.utils (441 files)
2026-01-25 00:23:03.912444 | controller | Including collection: arista.eos (1022 files)
2026-01-25 00:23:03.957417 | controller | Including collection: cisco.ios (1174 files)
2026-01-25 00:23:04.005666 | controller | Including collection: cisco.iosxr (1044 files)
2026-01-25 00:23:04.082011 | controller | Including collection: cisco.nxos (1687 files)
2026-01-25 00:23:04.127423 | controller | Including collection: junipernetworks.junos (963 files)
2026-01-25 00:23:04.175044 | controller | Creating a payload archive containing 7367 files...
2026-01-25 00:23:06.280185 | controller | Created a 6007961 byte payload archive containing 7367 files in 2 seconds.
2026-01-25 00:23:06.284164 | controller | Assuming Docker is available on localhost.
2026-01-25 00:23:06.284507 | controller | Run command: docker run --detach --volume /sys/fs/cgroup:/sys/fs/cgroup:ro --privileged=false --security-opt seccomp=unconfined --volume /var/run/docker.sock:/var/run/docker.sock quay.io/ansible/default-test-container:2.9.0
2026-01-25 00:23:06.835279 | controller | Run command: docker exec -i 048e60110de3c08bed57a95f091d7fb2cee8c7eb8265776c2997ec197fbb17ea dd of=/root/docker.sh bs=65536
2026-01-25 00:23:06.919166 | controller | Run command: docker exec 048e60110de3c08bed57a95f091d7fb2cee8c7eb8265776c2997ec197fbb17ea /bin/bash /root/docker.sh
2026-01-25 00:23:07.009757 | controller | Run command: docker exec -i 048e60110de3c08bed57a95f091d7fb2cee8c7eb8265776c2997ec197fbb17ea dd of=/root/test.tgz bs=65536
2026-01-25 00:23:07.111327 | controller | Run command: docker exec 048e60110de3c08bed57a95f091d7fb2cee8c7eb8265776c2997ec197fbb17ea tar oxzf /root/test.tgz -C /root
2026-01-25 00:23:08.138321 | controller | Run command: docker exec 048e60110de3c08bed57a95f091d7fb2cee8c7eb8265776c2997ec197fbb17ea /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-6qge0q34.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2026-01-25 00:23:08.548194 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2026-01-25 00:23:08.548607 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2026-01-25 00:23:08.549129 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2026-01-25 00:23:08.551154 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2026-01-25 00:23:08.552080 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2026-01-25 00:23:08.558982 | controller | No tests applicable.
2026-01-25 00:23:08.559259 | controller | Running sanity test 'ansible-doc' with Python 3.6
2026-01-25 00:23:08.559277 | controller | No tests applicable.
2026-01-25 00:23:08.559386 | controller | Running sanity test 'changelog' with Python 3.6
2026-01-25 00:23:08.560009 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/ansible-test.txt
2026-01-25 00:23:09.526660 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt
2026-01-25 00:23:10.133428 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2026-01-25 00:23:25.153876 | controller |   WARNING: Retrying (Retry(total=4, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2026-01-25 00:23:40.672445 | controller |   WARNING: Retrying (Retry(total=3, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2026-01-25 00:23:56.688703 | controller |   WARNING: Retrying (Retry(total=2, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2026-01-25 00:24:13.694069 | controller |   WARNING: Retrying (Retry(total=1, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2026-01-25 00:24:32.713855 | controller |   WARNING: Retrying (Retry(total=0, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2026-01-25 00:24:47.731215 | controller |   ERROR: Could not find a version that satisfies the requirement antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2)) (from versions: none)
2026-01-25 00:24:47.732466 | controller | ERROR: No matching distribution found for antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2026-01-25 00:24:47.840711 | controller | ERROR: Command "/usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt" returned exit status 1.
2026-01-25 00:24:47.890921 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2026-01-25 00:24:47.890968 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2026-01-25 00:24:47.890977 | controller | Removing custom PyPI hosts entries: /etc/hosts
2026-01-25 00:24:47.890992 | controller | Run command: docker exec 048e60110de3c08bed57a95f091d7fb2cee8c7eb8265776c2997ec197fbb17ea tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2026-01-25 00:24:47.969359 | controller | Run command: docker exec -i 048e60110de3c08bed57a95f091d7fb2cee8c7eb8265776c2997ec197fbb17ea dd if=/root/output.tgz bs=65536
2026-01-25 00:24:48.042331 | controller | Run command: tar oxzf /tmp/ansible-result-fz2ezwr5.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2026-01-25 00:24:48.062398 | controller | Run command: docker rm -f 9296fcbe8d7b33abc0ccb6a19df4f6aed392afe423d195a2c653e5352c4678ee
2026-01-25 00:24:48.344308 | controller | Run command: docker rm -f 048e60110de3c08bed57a95f091d7fb2cee8c7eb8265776c2997ec197fbb17ea
2026-01-25 00:24:49.045803 | controller | ERROR: Command "docker exec 048e60110de3c08bed57a95f091d7fb2cee8c7eb8265776c2997ec197fbb17ea /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-6qge0q34.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2026-01-25 00:24:49.260761 | controller | ERROR
2026-01-25 00:24:49.261069 | controller | {
2026-01-25 00:24:49.261139 | controller |   "delta": "0:03:27.013823",
2026-01-25 00:24:49.261184 | controller |   "end": "2026-01-25 00:24:49.106673",
2026-01-25 00:24:49.261223 | controller |   "msg": "non-zero return code",
2026-01-25 00:24:49.261260 | controller |   "rc": 1,
2026-01-25 00:24:49.261297 | controller |   "start": "2026-01-25 00:21:22.092850"
2026-01-25 00:24:49.261335 | controller | }
failure
2026-01-25 00:24:49.268191 | 
2026-01-25 00:24:49.268301 | PLAY RECAP
2026-01-25 00:24:49.268389 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2026-01-25 00:24:49.268447 | 
2026-01-25 00:24:49.410781 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-01-25 00:24:49.412861 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-25 00:24:50.060166 | 
2026-01-25 00:24:50.060329 | PLAY [all]
2026-01-25 00:24:50.083991 | 
2026-01-25 00:24:50.084178 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-01-25 00:24:53.055945 | controller | changed: non-zero return code
2026-01-25 00:24:53.062647 | 
2026-01-25 00:24:53.062745 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-01-25 00:24:53.078614 | controller | skipping: Conditional result was False
2026-01-25 00:24:53.085831 | 
2026-01-25 00:24:53.085946 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-01-25 00:24:53.121391 | 
2026-01-25 00:24:53.121605 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-01-25 00:24:53.154303 | 
2026-01-25 00:24:53.154497 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-01-25 00:24:53.179527 | controller | skipping: Conditional result was False
2026-01-25 00:24:53.186674 | 
2026-01-25 00:24:53.186755 | LOOP [fetch-subunit-output : Generate subunit file]
2026-01-25 00:24:53.217916 | 
2026-01-25 00:24:53.218094 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-01-25 00:24:53.243003 | controller | skipping: Conditional result was False
2026-01-25 00:24:53.253581 | 
2026-01-25 00:24:53.253690 | TASK [fetch-subunit-output : Remove the temporary file]
2026-01-25 00:24:53.279533 | controller | skipping: Conditional result was False
2026-01-25 00:24:53.287185 | 
2026-01-25 00:24:53.287289 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-01-25 00:24:53.302898 | controller | skipping: Conditional result was False
2026-01-25 00:24:53.332870 | 
2026-01-25 00:24:53.332995 | PLAY RECAP
2026-01-25 00:24:53.333085 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-25 00:24:53.333109 | 
2026-01-25 00:24:53.459552 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-25 00:24:53.460509 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-01-25 00:24:54.097699 | 
2026-01-25 00:24:54.097832 | PLAY [all:!appliance*]
2026-01-25 00:24:54.125495 | 
2026-01-25 00:24:54.125675 | TASK [unregister the node]
2026-01-25 00:24:54.480711 | controller | skipping: Conditional result was False
2026-01-25 00:24:54.486723 | 
2026-01-25 00:24:54.486805 | TASK [include_role : fetch-output]
2026-01-25 00:24:54.529922 | controller | ok
2026-01-25 00:24:54.585401 | 
2026-01-25 00:24:54.585555 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-25 00:24:54.631886 | controller | skipping: Conditional result was False
2026-01-25 00:24:54.638332 | 
2026-01-25 00:24:54.638438 | TASK [fetch-output : Set log path for single node]
2026-01-25 00:24:54.689117 | controller | ok
2026-01-25 00:24:54.696331 | 
2026-01-25 00:24:54.696473 | LOOP [fetch-output : Ensure local output dirs]
2026-01-25 00:24:55.141416 | controller -> localhost | ok: "/var/lib/zuul/builds/5c7d82c378fd4cadb444a887b450744c/work/logs"
2026-01-25 00:24:55.426109 | controller -> localhost | changed: "/var/lib/zuul/builds/5c7d82c378fd4cadb444a887b450744c/work/artifacts"
2026-01-25 00:24:55.672836 | controller -> localhost | changed: "/var/lib/zuul/builds/5c7d82c378fd4cadb444a887b450744c/work/docs"
2026-01-25 00:24:55.700965 | 
2026-01-25 00:24:55.701208 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-25 00:24:57.978810 | controller | changed:
2026-01-25 00:24:57.986545 | controller | .d..t...... ./
2026-01-25 00:24:57.986706 | controller | cd+++++++++ controller/
2026-01-25 00:24:57.986779 | controller | changed: All items complete
2026-01-25 00:24:57.986820 | 
2026-01-25 00:25:00.157491 | controller | changed: .d..t...... ./
2026-01-25 00:25:02.185917 | controller | changed: .d..t...... ./
2026-01-25 00:25:02.209298 | 
2026-01-25 00:25:02.209460 | TASK [include_role : fetch-output-openshift]
2026-01-25 00:25:02.225365 | controller | skipping: Conditional result was False
2026-01-25 00:25:02.233658 | 
2026-01-25 00:25:02.233741 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-25 00:25:02.264351 | controller | skipping: Conditional result was False
2026-01-25 00:25:02.274447 | controller | skipping: Conditional result was False
2026-01-25 00:25:02.307996 | 
2026-01-25 00:25:02.308483 | PLAY [localhost]
2026-01-25 00:25:02.324491 | 
2026-01-25 00:25:02.324668 | TASK [Run Zuul manifest role]
2026-01-25 00:25:02.345115 | localhost | ok
2026-01-25 00:25:02.360356 | 
2026-01-25 00:25:02.360446 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-25 00:25:02.710586 | localhost | changed
2026-01-25 00:25:02.719969 | 
2026-01-25 00:25:02.720102 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-25 00:25:02.754857 | localhost | ok
2026-01-25 00:25:02.768003 | 
2026-01-25 00:25:02.768153 | TASK [Set zuul-log-path fact]
2026-01-25 00:25:02.797952 | localhost | ok
2026-01-25 00:25:02.859618 | 
2026-01-25 00:25:02.859755 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-25 00:25:02.889817 | localhost | ok
2026-01-25 00:25:02.898254 | 
2026-01-25 00:25:02.898326 | LOOP [Run upload-logs-swift role]
2026-01-25 00:25:02.922903 | localhost | Output suppressed because no_log was given
2026-01-25 00:25:02.962303 | 
2026-01-25 00:25:02.962404 | TASK [Set zuul-log-path fact]
2026-01-25 00:25:02.987560 | localhost | skipping: Conditional result was False
2026-01-25 00:25:02.993392 | 
2026-01-25 00:25:02.993470 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-01-25 00:25:03.372166 | localhost -> localhost | ok: Runtime: 0:00:00.005026
2026-01-25 00:25:03.380743 | 
2026-01-25 00:25:03.380913 | TASK [upload-logs-swift : Upload logs to swift]