2026-02-22 00:36:38.784923 | Job console starting...
2026-02-22 00:36:38.799165 | Updating repositories
2026-02-22 00:36:38.948048 | Preparing job workspace
2026-02-22 00:36:43.999211 | Running Ansible setup...
2026-02-22 00:36:52.070134 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-02-22 00:36:52.813949 | 
2026-02-22 00:36:52.814112 | PLAY [localhost]
2026-02-22 00:36:52.822976 | 
2026-02-22 00:36:52.823069 | TASK [Gathering Facts]
2026-02-22 00:36:53.800079 | localhost | ok
2026-02-22 00:36:53.817767 | 
2026-02-22 00:36:53.817907 | TASK [Setup log path fact]
2026-02-22 00:36:53.838301 | localhost | ok
2026-02-22 00:36:53.853575 | 
2026-02-22 00:36:53.853758 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-22 00:36:53.883330 | localhost | ok
2026-02-22 00:36:53.892662 | 
2026-02-22 00:36:53.892814 | TASK [emit-job-header : Print job information]
2026-02-22 00:36:53.935462 | # Job Information
2026-02-22 00:36:53.935709 | Ansible Version: 2.15.12
2026-02-22 00:36:53.935791 | Job: ansible-test-sanity-docker-stable-2.9
2026-02-22 00:36:53.935830 | Pipeline: periodic
2026-02-22 00:36:53.935870 | Executor: ze03.softwarefactory-project.io
2026-02-22 00:36:53.935901 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-02-22 00:36:53.935943 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/86f/ansible/86f441b118fe4a368c3476d0c2e82540/
2026-02-22 00:36:53.935982 | Event ID: a7fc77fcb26d4cceb2ed771a560774dd
2026-02-22 00:36:53.942175 | 
2026-02-22 00:36:53.942298 | LOOP [emit-job-header : Print node information]
2026-02-22 00:36:54.042443 | localhost | ok:
2026-02-22 00:36:54.042686 | localhost | # Node Information
2026-02-22 00:36:54.042719 | localhost | Inventory Hostname: controller
2026-02-22 00:36:54.042763 | localhost | Hostname: np0005625950
2026-02-22 00:36:54.042784 | localhost | Username: zuul
2026-02-22 00:36:54.042815 | localhost | Distro: Fedora 37
2026-02-22 00:36:54.042842 | localhost | Provider: ansible-vexxhost-ams1
2026-02-22 00:36:54.042866 | localhost | Region: ams1
2026-02-22 00:36:54.042885 | localhost | Label: ansible-fedora-37-1vcpu
2026-02-22 00:36:54.042902 | localhost | Product Name: OpenStack Nova
2026-02-22 00:36:54.042920 | localhost | Interface IP: 38.129.16.230
2026-02-22 00:36:54.059560 | 
2026-02-22 00:36:54.059713 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-22 00:36:54.483294 | localhost -> localhost | changed
2026-02-22 00:36:54.489822 | 
2026-02-22 00:36:54.489907 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-22 00:36:55.475637 | localhost -> localhost | changed
2026-02-22 00:36:55.512675 | 
2026-02-22 00:36:55.512883 | PLAY [all:!appliance*]
2026-02-22 00:36:55.536636 | 
2026-02-22 00:36:55.536840 | TASK [include_role : start-zuul-console]
2026-02-22 00:36:55.557798 | controller | ok
2026-02-22 00:36:55.586827 | 
2026-02-22 00:36:55.586998 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-02-22 00:36:56.752105 | controller | ok
2026-02-22 00:36:56.771110 | 
2026-02-22 00:36:56.771299 | TASK [use-our-mirror : Retrieve the IP address]
2026-02-22 00:36:59.309557 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-02-22 00:36:59.324454 | 
2026-02-22 00:36:59.324603 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-02-22 00:36:59.697844 | controller | skipping: Conditional result was False
2026-02-22 00:36:59.711564 | 
2026-02-22 00:36:59.711793 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-02-22 00:36:59.749319 | controller | skipping: Conditional result was False
2026-02-22 00:36:59.764648 | 
2026-02-22 00:36:59.764884 | TASK [use-our-mirror : Create the podman configuration directory]
2026-02-22 00:36:59.792156 | controller | skipping: Conditional result was False
2026-02-22 00:36:59.806518 | 
2026-02-22 00:36:59.806700 | TASK [use-our-mirror : Copy the podman configuration]
2026-02-22 00:36:59.834706 | controller | skipping: Conditional result was False
2026-02-22 00:36:59.850262 | 
2026-02-22 00:36:59.850435 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-02-22 00:36:59.877249 | controller | skipping: Conditional result was False
2026-02-22 00:36:59.892000 | 
2026-02-22 00:36:59.892210 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-02-22 00:36:59.929711 | controller | skipping: Conditional result was False
2026-02-22 00:36:59.953797 | 
2026-02-22 00:36:59.953995 | TASK [Disable Fedora Modular]
2026-02-22 00:37:01.132831 | controller | changed
2026-02-22 00:37:01.144946 | 
2026-02-22 00:37:01.145165 | TASK [Enable EPEL]
2026-02-22 00:37:01.172115 | controller | skipping: Conditional result was False
2026-02-22 00:37:01.182323 | 
2026-02-22 00:37:01.182462 | TASK [Register the RHEL node]
2026-02-22 00:37:01.589895 | 
2026-02-22 00:37:01.590185 | TASK [Show the subscription-manager status]
2026-02-22 00:37:01.966461 | controller | skipping: Conditional result was False
2026-02-22 00:37:01.981939 | 
2026-02-22 00:37:01.982094 | TASK [Enable EPEL on RHEL]
2026-02-22 00:37:02.367704 | controller | skipping: Conditional result was False
2026-02-22 00:37:02.382403 | 
2026-02-22 00:37:02.382619 | TASK [Install git and tox]
2026-02-22 00:39:17.675325 | controller | changed
2026-02-22 00:39:17.684494 | 
2026-02-22 00:39:17.684588 | TASK [include_role : prepare-workspace]
2026-02-22 00:39:17.723415 | controller | ok
2026-02-22 00:39:17.757980 | 
2026-02-22 00:39:17.758093 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-22 00:39:18.612358 | controller | ok
2026-02-22 00:39:18.619050 | 
2026-02-22 00:39:18.619139 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-22 00:39:40.318595 | controller | Output suppressed because no_log was given
2026-02-22 00:39:40.331950 | 
2026-02-22 00:39:40.332023 | TASK [include_role : prepare-workspace-openshift]
2026-02-22 00:39:40.353225 | controller | skipping: Conditional result was False
2026-02-22 00:39:40.386505 | 
2026-02-22 00:39:40.386613 | PLAY [all:!appliance]
2026-02-22 00:39:40.409904 | 
2026-02-22 00:39:40.410023 | TASK [Run add-build-sshkey role (RSA)]
2026-02-22 00:39:40.430864 | controller | ok
2026-02-22 00:39:40.446403 | 
2026-02-22 00:39:40.446538 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-22 00:39:40.659290 | controller -> localhost | ok
2026-02-22 00:39:40.675977 | 
2026-02-22 00:39:40.676156 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-22 00:39:40.701319 | controller | ok
2026-02-22 00:39:40.722046 | controller | included: /var/lib/zuul/builds/86f441b118fe4a368c3476d0c2e82540/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-22 00:39:40.731192 | 
2026-02-22 00:39:40.731305 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-22 00:39:41.317589 | controller -> localhost | Generating public/private rsa key pair.
2026-02-22 00:39:41.317830 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/86f441b118fe4a368c3476d0c2e82540/work/86f441b118fe4a368c3476d0c2e82540_id_rsa.
2026-02-22 00:39:41.317866 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/86f441b118fe4a368c3476d0c2e82540/work/86f441b118fe4a368c3476d0c2e82540_id_rsa.pub.
2026-02-22 00:39:41.317888 | controller -> localhost | The key fingerprint is:
2026-02-22 00:39:41.317910 | controller -> localhost | SHA256:rMH188Yp6fwbuWzunWsViDQVPwNxp7IqLf0w8pS8LhU zuul-build-sshkey
2026-02-22 00:39:41.317931 | controller -> localhost | The key's randomart image is:
2026-02-22 00:39:41.317951 | controller -> localhost | +---[RSA 2048]----+
2026-02-22 00:39:41.317971 | controller -> localhost | |            .=o..|
2026-02-22 00:39:41.317993 | controller -> localhost | |           o  +..|
2026-02-22 00:39:41.318012 | controller -> localhost | |        . . + o+ |
2026-02-22 00:39:41.318032 | controller -> localhost | |     . o .E. + .o|
2026-02-22 00:39:41.318051 | controller -> localhost | |      o S o..   .|
2026-02-22 00:39:41.318083 | controller -> localhost | |       o +.B o  .|
2026-02-22 00:39:41.318108 | controller -> localhost | |      . +.% B  . |
2026-02-22 00:39:41.318129 | controller -> localhost | |        .O O.+.. |
2026-02-22 00:39:41.318149 | controller -> localhost | |         o*=Oo+. |
2026-02-22 00:39:41.318171 | controller -> localhost | +----[SHA256]-----+
2026-02-22 00:39:41.318218 | controller -> localhost | ok: Runtime: 0:00:00.164068
2026-02-22 00:39:41.325051 | 
2026-02-22 00:39:41.325116 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-22 00:39:41.357917 | controller | ok
2026-02-22 00:39:41.367901 | controller | included: /var/lib/zuul/builds/86f441b118fe4a368c3476d0c2e82540/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-22 00:39:41.376841 | 
2026-02-22 00:39:41.376911 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-22 00:39:41.401326 | controller | skipping: Conditional result was False
2026-02-22 00:39:41.408419 | 
2026-02-22 00:39:41.408499 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-22 00:39:42.520402 | controller | changed
2026-02-22 00:39:42.526434 | 
2026-02-22 00:39:42.526501 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-22 00:39:43.200357 | controller | ok
2026-02-22 00:39:43.214490 | 
2026-02-22 00:39:43.214836 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-22 00:39:46.258969 | controller | changed
2026-02-22 00:39:46.267114 | 
2026-02-22 00:39:46.267201 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-22 00:39:49.297942 | controller | changed
2026-02-22 00:39:49.306185 | 
2026-02-22 00:39:49.306307 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-22 00:39:49.330921 | controller | skipping: Conditional result was False
2026-02-22 00:39:49.338017 | 
2026-02-22 00:39:49.338162 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-22 00:39:49.736860 | controller -> localhost | changed
2026-02-22 00:39:49.753517 | 
2026-02-22 00:39:49.753656 | TASK [add-build-sshkey : Add back temp key]
2026-02-22 00:39:50.025433 | controller -> localhost | Identity added: /var/lib/zuul/builds/86f441b118fe4a368c3476d0c2e82540/work/86f441b118fe4a368c3476d0c2e82540_id_rsa (zuul-build-sshkey)
2026-02-22 00:39:50.025808 | controller -> localhost | ok: Runtime: 0:00:00.007486
2026-02-22 00:39:50.032983 | 
2026-02-22 00:39:50.033073 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-22 00:39:50.917178 | controller | ok
2026-02-22 00:39:50.930399 | 
2026-02-22 00:39:50.930534 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-22 00:39:50.956919 | controller | skipping: Conditional result was False
2026-02-22 00:39:50.980307 | 
2026-02-22 00:39:50.980458 | TASK [Run add-build-sshkey role (ECDSA)]
2026-02-22 00:39:51.007802 | controller | ok
2026-02-22 00:39:51.037072 | 
2026-02-22 00:39:51.037222 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-22 00:39:51.310834 | controller -> localhost | ok
2026-02-22 00:39:51.325672 | 
2026-02-22 00:39:51.325906 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-22 00:39:51.368271 | controller | ok
2026-02-22 00:39:51.391415 | controller | included: /var/lib/zuul/builds/86f441b118fe4a368c3476d0c2e82540/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-22 00:39:51.400300 | 
2026-02-22 00:39:51.400392 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-22 00:39:51.762835 | controller -> localhost | Generating public/private ecdsa key pair.
2026-02-22 00:39:51.763191 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/86f441b118fe4a368c3476d0c2e82540/work/86f441b118fe4a368c3476d0c2e82540_id_ecdsa.
2026-02-22 00:39:51.763253 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/86f441b118fe4a368c3476d0c2e82540/work/86f441b118fe4a368c3476d0c2e82540_id_ecdsa.pub.
2026-02-22 00:39:51.763355 | controller -> localhost | The key fingerprint is:
2026-02-22 00:39:51.763404 | controller -> localhost | SHA256:9IUnPYX9vyU4OKUiJBF6bA03z+RK+q8Iv1kKzCdgYbs zuul-build-sshkey
2026-02-22 00:39:51.763447 | controller -> localhost | The key's randomart image is:
2026-02-22 00:39:51.763488 | controller -> localhost | +---[ECDSA 521]---+
2026-02-22 00:39:51.763529 | controller -> localhost | |    o.o .    o.  |
2026-02-22 00:39:51.763569 | controller -> localhost | |   o.+ *   o...  |
2026-02-22 00:39:51.763609 | controller -> localhost | | o. +.o = o =  . |
2026-02-22 00:39:51.763648 | controller -> localhost | |. oo.o.o . +..  .|
2026-02-22 00:39:51.763687 | controller -> localhost | |.o  .o. S .+ .  .|
2026-02-22 00:39:51.763726 | controller -> localhost | |.+.  .. . + o . o|
2026-02-22 00:39:51.763804 | controller -> localhost | | E* . o. . . . .o|
2026-02-22 00:39:51.763846 | controller -> localhost | |   * = .       . |
2026-02-22 00:39:51.763885 | controller -> localhost | |    *....        |
2026-02-22 00:39:51.763924 | controller -> localhost | +----[SHA256]-----+
2026-02-22 00:39:51.764019 | controller -> localhost | ok: Runtime: 0:00:00.014848
2026-02-22 00:39:51.779153 | 
2026-02-22 00:39:51.779285 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-22 00:39:51.808794 | controller | ok
2026-02-22 00:39:51.824798 | controller | included: /var/lib/zuul/builds/86f441b118fe4a368c3476d0c2e82540/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-22 00:39:51.836829 | 
2026-02-22 00:39:51.836924 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-22 00:39:51.862487 | controller | skipping: Conditional result was False
2026-02-22 00:39:51.872124 | 
2026-02-22 00:39:51.872220 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-22 00:39:52.783801 | controller | changed
2026-02-22 00:39:52.796657 | 
2026-02-22 00:39:52.796860 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-22 00:39:53.477976 | controller | ok
2026-02-22 00:39:53.494574 | 
2026-02-22 00:39:53.494869 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-22 00:39:56.567386 | controller | changed
2026-02-22 00:39:56.576048 | 
2026-02-22 00:39:56.576141 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-22 00:39:59.642179 | controller | changed
2026-02-22 00:39:59.651858 | 
2026-02-22 00:39:59.651952 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-22 00:39:59.677102 | controller | skipping: Conditional result was False
2026-02-22 00:39:59.688216 | 
2026-02-22 00:39:59.688403 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-22 00:39:59.941108 | controller -> localhost | changed
2026-02-22 00:39:59.970538 | 
2026-02-22 00:39:59.970770 | TASK [add-build-sshkey : Add back temp key]
2026-02-22 00:40:00.285755 | controller -> localhost | Identity added: /var/lib/zuul/builds/86f441b118fe4a368c3476d0c2e82540/work/86f441b118fe4a368c3476d0c2e82540_id_ecdsa (zuul-build-sshkey)
2026-02-22 00:40:00.286058 | controller -> localhost | ok: Runtime: 0:00:00.010913
2026-02-22 00:40:00.294445 | 
2026-02-22 00:40:00.294629 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-22 00:40:01.035587 | controller | ok
2026-02-22 00:40:01.048759 | 
2026-02-22 00:40:01.048912 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-22 00:40:01.090584 | controller | skipping: Conditional result was False
2026-02-22 00:40:01.117524 | 
2026-02-22 00:40:01.117795 | TASK [include_role : remove-zuul-sshkey]
2026-02-22 00:40:01.159990 | controller | skipping: Conditional result was False
2026-02-22 00:40:01.175922 | 
2026-02-22 00:40:01.176226 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-22 00:40:01.967541 | controller | ok: "logs"
2026-02-22 00:40:01.968058 | controller | ok: All items complete
2026-02-22 00:40:01.968183 | 
2026-02-22 00:40:02.588016 | controller | ok: "artifacts"
2026-02-22 00:40:03.225657 | controller | ok: "docs"
2026-02-22 00:40:03.236005 | 
2026-02-22 00:40:03.236134 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-22 00:40:03.912390 | controller | changed: "logs"
2026-02-22 00:40:04.536717 | controller | changed: "artifacts"
2026-02-22 00:40:05.189554 | controller | changed: "docs"
2026-02-22 00:40:05.260537 | 
2026-02-22 00:40:05.260656 | PLAY RECAP
2026-02-22 00:40:05.260700 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-02-22 00:40:05.260747 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-22 00:40:05.260771 | 
2026-02-22 00:40:05.395508 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-02-22 00:40:05.398381 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-02-22 00:40:06.249940 | 
2026-02-22 00:40:06.250065 | PLAY [all]
2026-02-22 00:40:06.272355 | 
2026-02-22 00:40:06.272448 | TASK [Install binary dependencies]
2026-02-22 00:40:06.323348 | controller | ok
2026-02-22 00:40:06.343860 | 
2026-02-22 00:40:06.343957 | TASK [bindep : Include find tasks]
2026-02-22 00:40:06.375041 | controller | ok
2026-02-22 00:40:06.383538 | controller | included: /var/lib/zuul/builds/86f441b118fe4a368c3476d0c2e82540/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-02-22 00:40:06.389894 | 
2026-02-22 00:40:06.389959 | TASK [bindep : Look for bindep.txt]
2026-02-22 00:40:08.879661 | controller | ok
2026-02-22 00:40:08.887883 | 
2026-02-22 00:40:08.887978 | TASK [bindep : Define bindep_file fact]
2026-02-22 00:40:08.914190 | controller | skipping: Conditional result was False
2026-02-22 00:40:08.923841 | 
2026-02-22 00:40:08.923940 | TASK [bindep : Look for other-requirements.txt]
2026-02-22 00:40:10.002376 | controller | ok
2026-02-22 00:40:10.007808 | 
2026-02-22 00:40:10.007874 | TASK [bindep : Define bindep_file fact]
2026-02-22 00:40:10.032239 | controller | skipping: Conditional result was False
2026-02-22 00:40:10.045918 | 
2026-02-22 00:40:10.046067 | TASK [bindep : Look for bindep fallback file]
2026-02-22 00:40:10.074536 | controller | skipping: Conditional result was False
2026-02-22 00:40:10.089474 | 
2026-02-22 00:40:10.089629 | TASK [bindep : Define bindep_file fact]
2026-02-22 00:40:10.116337 | controller | skipping: Conditional result was False
2026-02-22 00:40:10.126281 | 
2026-02-22 00:40:10.126402 | TASK [bindep : Include bindep tasks]
2026-02-22 00:40:10.161884 | controller | skipping: Conditional result was False
2026-02-22 00:40:10.176785 | 
2026-02-22 00:40:10.176952 | TASK [bindep : Include install tasks]
2026-02-22 00:40:10.202743 | controller | skipping: Conditional result was False
2026-02-22 00:40:10.213918 | 
2026-02-22 00:40:10.214053 | LOOP [bindep : Include package tasks]
2026-02-22 00:40:10.275804 | 
2026-02-22 00:40:10.276147 | TASK [Run test-setup role]
2026-02-22 00:40:10.300025 | controller | ok
2026-02-22 00:40:10.330842 | 
2026-02-22 00:40:10.331010 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-02-22 00:40:11.390226 | controller | ok
2026-02-22 00:40:11.403454 | 
2026-02-22 00:40:11.403595 | TASK [test-setup : Run tools/test-setup.sh]
2026-02-22 00:40:11.775508 | controller | skipping: Conditional result was False
2026-02-22 00:40:11.833493 | 
2026-02-22 00:40:11.833614 | PLAY RECAP
2026-02-22 00:40:11.833669 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-02-22 00:40:11.833697 | 
2026-02-22 00:40:11.983537 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-02-22 00:40:11.984431 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-02-22 00:40:12.551170 | 
2026-02-22 00:40:12.551298 | PLAY [controller]
2026-02-22 00:40:12.577210 | 
2026-02-22 00:40:12.577375 | TASK [Create the /root directory]
2026-02-22 00:40:13.776860 | controller | ok
2026-02-22 00:40:13.792869 | 
2026-02-22 00:40:13.793037 | TASK [Install glibc-langpack-en]
2026-02-22 00:40:22.994520 | controller | ok: Nothing to do
2026-02-22 00:40:23.000237 | 
2026-02-22 00:40:23.000306 | TASK [Ensure controller directory exists]
2026-02-22 00:40:23.916161 | controller | changed
2026-02-22 00:40:23.923281 | 
2026-02-22 00:40:23.923383 | TASK [Install container runtime]
2026-02-22 00:40:23.964794 | controller | ok
2026-02-22 00:40:24.003955 | 
2026-02-22 00:40:24.004070 | LOOP [ensure-docker : Gather variables for each operating system]
2026-02-22 00:40:24.068885 | controller | ok: "/var/lib/zuul/builds/86f441b118fe4a368c3476d0c2e82540/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2026-02-22 00:40:24.086204 | 
2026-02-22 00:40:24.086338 | TASK [ensure-docker : Sanity check for distro selection]
2026-02-22 00:40:24.111121 | controller | skipping: Conditional result was False
2026-02-22 00:40:24.118409 | 
2026-02-22 00:40:24.118505 | TASK [ensure-docker : Sanity check for distro install]
2026-02-22 00:40:24.159710 | controller | skipping: Conditional result was False
2026-02-22 00:40:24.166126 | 
2026-02-22 00:40:24.166201 | TASK [ensure-docker : Sanity check for upstream install]
2026-02-22 00:40:24.191286 | controller | skipping: Conditional result was False
2026-02-22 00:40:24.197640 | 
2026-02-22 00:40:24.197710 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2026-02-22 00:40:24.221853 | controller | skipping: Conditional result was False
2026-02-22 00:40:24.227945 | 
2026-02-22 00:40:24.228013 | TASK [ensure-docker : Include tasks for docker install]
2026-02-22 00:40:24.258685 | controller | ok
2026-02-22 00:40:24.266812 | controller | included: /var/lib/zuul/builds/86f441b118fe4a368c3476d0c2e82540/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2026-02-22 00:40:24.274856 | 
2026-02-22 00:40:24.274923 | TASK [ensure-docker : Not Implemented]
2026-02-22 00:40:24.300100 | controller | skipping: Conditional result was False
2026-02-22 00:40:24.309177 | 
2026-02-22 00:40:24.309267 | TASK [Set up docker mirrors]
2026-02-22 00:40:24.341587 | controller | ok
2026-02-22 00:40:24.399309 | 
2026-02-22 00:40:24.399454 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2026-02-22 00:40:24.444182 | controller | skipping: Conditional result was False
2026-02-22 00:40:24.450579 | 
2026-02-22 00:40:24.450651 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2026-02-22 00:40:24.485847 | controller | skipping: Conditional result was False
2026-02-22 00:40:24.492436 | 
2026-02-22 00:40:24.492506 | TASK [use-docker-mirror : Set docker_mirror fact]
2026-02-22 00:40:24.527038 | controller | skipping: Conditional result was False
2026-02-22 00:40:24.536048 | 
2026-02-22 00:40:24.536170 | TASK [use-docker-mirror : Set up docker mirrors]
2026-02-22 00:40:24.562296 | controller | skipping: Conditional result was False
2026-02-22 00:40:24.579701 | 
2026-02-22 00:40:24.579823 | TASK [Add all repositories]
2026-02-22 00:40:24.606535 | controller | ok
2026-02-22 00:40:24.635175 | 
2026-02-22 00:40:24.635277 | LOOP [ensure-package-repositories : Install repositories]
2026-02-22 00:40:24.677711 | controller | ok: "/var/lib/zuul/builds/86f441b118fe4a368c3476d0c2e82540/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2026-02-22 00:40:24.678117 | controller | ok: All items complete
2026-02-22 00:40:24.678208 | 
2026-02-22 00:40:24.691443 | controller | included: /var/lib/zuul/builds/86f441b118fe4a368c3476d0c2e82540/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2026-02-22 00:40:24.697536 | 
2026-02-22 00:40:24.697597 | TASK [ensure-package-repositories : Create GPG key temp directory]
2026-02-22 00:40:25.714584 | controller | changed
2026-02-22 00:40:25.728148 | 
2026-02-22 00:40:25.728309 | LOOP [ensure-package-repositories : Upload GPG keys]
2026-02-22 00:40:29.387196 | controller | changed:
2026-02-22 00:40:29.387602 | controller | {
2026-02-22 00:40:29.387661 | 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-02-22 00:40:29.387707 | controller | }
2026-02-22 00:40:29.411542 | 
2026-02-22 00:40:29.411836 | LOOP [ensure-package-repositories : Import GPG keys]
2026-02-22 00:40:30.992222 | controller | changed:
2026-02-22 00:40:30.992604 | controller | {
2026-02-22 00:40:30.992676 | 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-02-22 00:40:30.992765 | controller | }
2026-02-22 00:40:30.992852 | controller | changed: All items complete
2026-02-22 00:40:30.992905 | 
2026-02-22 00:40:31.008908 | 
2026-02-22 00:40:31.009196 | TASK [ensure-package-repositories : Remove GPG temp directory]
2026-02-22 00:40:31.976318 | controller | changed
2026-02-22 00:40:31.994243 | 
2026-02-22 00:40:31.994522 | LOOP [ensure-package-repositories : Add repositories]
2026-02-22 00:40:33.129029 | controller | changed:
2026-02-22 00:40:33.129481 | controller | {
2026-02-22 00:40:33.129555 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2026-02-22 00:40:33.129611 | controller |   "description": "Docker CE Stable - $basearch",
2026-02-22 00:40:33.129663 | controller |   "gpgcheck": true,
2026-02-22 00:40:33.129705 | controller |   "name": "docker-ce-stable"
2026-02-22 00:40:33.129840 | controller | }
2026-02-22 00:40:33.166345 | 
2026-02-22 00:40:33.166556 | TASK [ensure-docker : Install pre-reqs]
2026-02-22 00:40:38.622691 | controller | ok: Nothing to do
2026-02-22 00:40:38.630444 | 
2026-02-22 00:40:38.630529 | TASK [ensure-docker : Remove packages]
2026-02-22 00:40:42.027766 | controller | ok: Nothing to do
2026-02-22 00:40:42.040919 | 
2026-02-22 00:40:42.041069 | TASK [ensure-docker : Install upstream docker using package]
2026-02-22 00:42:08.480686 | controller | changed
2026-02-22 00:42:08.491520 | 
2026-02-22 00:42:08.491626 | TASK [ensure-docker : Setup docker]
2026-02-22 00:42:08.516192 | controller | ok
2026-02-22 00:42:08.568206 | controller | included: /var/lib/zuul/builds/86f441b118fe4a368c3476d0c2e82540/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2026-02-22 00:42:08.580082 | 
2026-02-22 00:42:08.580150 | TASK [ensure-docker : Ensure "docker" group exists]
2026-02-22 00:42:09.541450 | controller | ok
2026-02-22 00:42:09.549201 | 
2026-02-22 00:42:09.549295 | TASK [ensure-docker : Add user to docker group]
2026-02-22 00:42:10.622868 | controller | changed
2026-02-22 00:42:10.628896 | 
2026-02-22 00:42:10.628964 | TASK [Update docker daemon proxy configuration]
2026-02-22 00:42:10.663831 | controller | skipping: Conditional result was False
2026-02-22 00:42:10.671489 | 
2026-02-22 00:42:10.671582 | TASK [Lower default MTU]
2026-02-22 00:42:10.685898 | controller | skipping: Conditional result was False
2026-02-22 00:42:10.702281 | 
2026-02-22 00:42:10.702490 | TASK [ensure-docker : Get the status of the docket.socket unit]
2026-02-22 00:42:11.587997 | controller | inactive
2026-02-22 00:42:12.079863 | controller | ok: Runtime: 0:00:00.019216
2026-02-22 00:42:12.094853 | 
2026-02-22 00:42:12.095047 | TASK [ensure-docker : Get the status of the docket unit]
2026-02-22 00:42:12.991880 | controller | inactive
2026-02-22 00:42:13.471405 | controller | ok: Runtime: 0:00:00.020693
2026-02-22 00:42:13.486289 | 
2026-02-22 00:42:13.486490 | TASK [ensure-docker : Restart docker]
2026-02-22 00:42:13.547280 | Trigger a Docker restart
2026-02-22 00:42:13.559622 | 
2026-02-22 00:42:13.559798 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2026-02-22 00:42:13.610944 | 
2026-02-22 00:42:13.611171 | TASK [ensure-docker : Install docker-compose]
2026-02-22 00:42:13.638824 | controller | skipping: Conditional result was False
2026-02-22 00:42:13.647950 | 
2026-02-22 00:42:13.648105 | TASK [ensure-docker : Flush handlers before role exit]
2026-02-22 00:42:13.662342 | 
2026-02-22 00:42:13.662428 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2026-02-22 00:42:17.203033 | controller | changed
2026-02-22 00:42:17.213112 | 
2026-02-22 00:42:17.213202 | TASK [ensure-docker : Assure docker service is running]
2026-02-22 00:42:21.154859 | controller | changed
2026-02-22 00:42:21.172652 | 
2026-02-22 00:42:21.172887 | TASK [ensure-docker : Assure docker.socket service is running]
2026-02-22 00:42:22.695497 | controller | ok
2026-02-22 00:42:22.715383 | 
2026-02-22 00:42:22.715597 | TASK [ensure-docker : Correct group ownership on docker sock]
2026-02-22 00:42:23.458134 | controller | ok
2026-02-22 00:42:23.472286 | 
2026-02-22 00:42:23.472436 | TASK [ensure-docker : Validate ability to talk with docker]
2026-02-22 00:42:24.109510 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2026-02-22 00:42:24.347155 | controller | ok: Runtime: 0:00:00.042459
2026-02-22 00:42:24.361243 | 
2026-02-22 00:42:24.361383 | TASK [ensure-docker : Get version details]
2026-02-22 00:42:25.110772 | controller | Client: Docker Engine - Community
2026-02-22 00:42:25.110888 | controller |  Version:           24.0.7
2026-02-22 00:42:25.110899 | controller |  API version:       1.43
2026-02-22 00:42:25.110910 | controller |  Go version:        go1.20.10
2026-02-22 00:42:25.110928 | controller |  Git commit:        afdd53b
2026-02-22 00:42:25.110936 | controller |  Built:             Thu Oct 26 09:09:39 2023
2026-02-22 00:42:25.110944 | controller |  OS/Arch:           linux/amd64
2026-02-22 00:42:25.110952 | controller |  Context:           default
2026-02-22 00:42:25.110961 | controller |
2026-02-22 00:42:25.110968 | controller | Server: Docker Engine - Community
2026-02-22 00:42:25.110975 | controller |  Engine:
2026-02-22 00:42:25.110985 | controller |   Version:          24.0.7
2026-02-22 00:42:25.111035 | controller |   API version:      1.43 (minimum version 1.12)
2026-02-22 00:42:25.111043 | controller |   Go version:       go1.20.10
2026-02-22 00:42:25.111049 | controller |   Git commit:       311b9ff
2026-02-22 00:42:25.111055 | controller |   Built:            Thu Oct 26 09:08:11 2023
2026-02-22 00:42:25.111071 | controller |   OS/Arch:          linux/amd64
2026-02-22 00:42:25.111077 | controller |   Experimental:     false
2026-02-22 00:42:25.111083 | controller |  containerd:
2026-02-22 00:42:25.111089 | controller |   Version:          1.6.25
2026-02-22 00:42:25.111095 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2026-02-22 00:42:25.111101 | controller |  runc:
2026-02-22 00:42:25.111110 | controller |   Version:          1.1.10
2026-02-22 00:42:25.114523 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2026-02-22 00:42:25.114543 | controller |  docker-init:
2026-02-22 00:42:25.114550 | controller |   Version:          0.19.0
2026-02-22 00:42:25.114649 | controller |   GitCommit:        de40ad0
2026-02-22 00:42:25.273359 | controller | ok: Runtime: 0:00:00.050025
2026-02-22 00:42:25.289278 | 
2026-02-22 00:42:25.289451 | TASK [ensure-docker : Dump installed docker details]
2026-02-22 00:42:25.333016 | {'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-02-22 00:42:25.065037', 'end': '2026-02-22 00:42:25.115062', 'delta': '0:00:00.050025', 'msg': '', 'zuul_log_id': 'fa163ef9-e89a-0cee-f8fa-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-02-22 00:42:25.345914 | 
2026-02-22 00:42:25.346036 | TASK [Use buildset registry]
2026-02-22 00:42:25.373471 | controller | skipping: Conditional result was False
2026-02-22 00:42:25.401186 | 
2026-02-22 00:42:25.401342 | TASK [Ensure python3.8 is present]
2026-02-22 00:42:25.427781 | controller | skipping: Conditional result was False
2026-02-22 00:42:25.441990 | 
2026-02-22 00:42:25.442148 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-02-22 00:42:25.477435 | controller | ok
2026-02-22 00:42:25.508119 | 
2026-02-22 00:42:25.508226 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-02-22 00:42:32.005512 | controller | ok: Nothing to do
2026-02-22 00:42:32.017619 | 
2026-02-22 00:42:32.017793 | TASK [our-ensure-python : Also install python3-devel]
2026-02-22 00:42:52.611977 | controller | changed
2026-02-22 00:42:52.637541 | 
2026-02-22 00:42:52.637771 | TASK [Run ensure-virtualenv role]
2026-02-22 00:42:52.662555 | controller | ok
2026-02-22 00:42:52.692670 | 
2026-02-22 00:42:52.692834 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-02-22 00:42:53.436652 | controller | /usr/bin/virtualenv
2026-02-22 00:42:54.052298 | controller | ok: Runtime: 0:00:00.004450
2026-02-22 00:42:54.066587 | 
2026-02-22 00:42:54.066862 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-02-22 00:42:54.121600 | controller | skipping: Conditional result was False
2026-02-22 00:42:54.122098 | controller | changed: All items complete
2026-02-22 00:42:54.122135 | 
2026-02-22 00:42:54.141033 | 
2026-02-22 00:42:54.141239 | TASK [Find the full path of the Python interpreter]
2026-02-22 00:42:54.928700 | controller | /usr/bin/python3.8
2026-02-22 00:42:55.524562 | controller | ok
2026-02-22 00:42:55.531344 | 
2026-02-22 00:42:55.531418 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-02-22 00:42:58.240061 | controller | created virtual environment CPython3.8.18.final.0-64 in 1388ms
2026-02-22 00:42:58.370692 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-02-22 00:42:58.370742 | 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-02-22 00:42:58.370755 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-02-22 00:42:58.370775 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-02-22 00:42:58.972334 | controller | changed
2026-02-22 00:42:58.981655 | 
2026-02-22 00:42:58.981782 | TASK [Set selinux package]
2026-02-22 00:42:59.018327 | controller | ok
2026-02-22 00:42:59.025765 | 
2026-02-22 00:42:59.025843 | TASK [Set selinux package (Fedora)]
2026-02-22 00:42:59.078107 | controller | ok
2026-02-22 00:42:59.087703 | 
2026-02-22 00:42:59.087815 | TASK [Install selinux into virtualenv]
2026-02-22 00:43:05.134723 | controller | Collecting selinux-please-lie-to-me
2026-02-22 00:43:05.269227 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-02-22 00:43:06.016752 | controller | Collecting setuptools<50.0.0
2026-02-22 00:43:06.022549 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-02-22 00:43:06.112353 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 9.6 MB/s eta 0:00:00
2026-02-22 00:43:06.252760 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-02-22 00:43:06.253149 | controller |   Attempting uninstall: setuptools
2026-02-22 00:43:06.254497 | controller |     Found existing installation: setuptools 62.6.0
2026-02-22 00:43:06.387516 | controller |     Uninstalling setuptools-62.6.0:
2026-02-22 00:43:06.404715 | controller |       Successfully uninstalled setuptools-62.6.0
2026-02-22 00:43:07.151067 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-02-22 00:43:07.377060 | controller |
2026-02-22 00:43:07.640719 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-02-22 00:43:07.640773 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-02-22 00:43:07.971626 | controller | ok: Runtime: 0:00:07.571075
2026-02-22 00:43:07.984508 | 
2026-02-22 00:43:07.984646 | TASK [Install pytest-forked into virtualenv]
2026-02-22 00:43:09.853346 | controller | Collecting pytest-forked
2026-02-22 00:43:09.918737 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-02-22 00:43:10.178702 | controller | Collecting pytest>=3.10
2026-02-22 00:43:10.187372 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2026-02-22 00:43:10.239690 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 7.5 MB/s eta 0:00:00
2026-02-22 00:43:10.319434 | controller | Collecting py
2026-02-22 00:43:10.326719 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-02-22 00:43:10.341450 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 11.7 MB/s eta 0:00:00
2026-02-22 00:43:10.426425 | controller | Collecting pluggy<2,>=1.5
2026-02-22 00:43:10.431549 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2026-02-22 00:43:10.480375 | controller | Collecting iniconfig
2026-02-22 00:43:10.486820 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2026-02-22 00:43:10.586577 | controller | Collecting packaging
2026-02-22 00:43:10.592890 | controller |   Downloading packaging-26.0-py3-none-any.whl (74 kB)
2026-02-22 00:43:10.609941 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 4.8 MB/s eta 0:00:00
2026-02-22 00:43:10.712538 | controller | Collecting tomli>=1
2026-02-22 00:43:10.718006 | controller |   Downloading tomli-2.4.0-py3-none-any.whl (14 kB)
2026-02-22 00:43:10.781689 | controller | Collecting exceptiongroup>=1.0.0rc8
2026-02-22 00:43:10.791589 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2026-02-22 00:43:10.887128 | controller | Collecting typing-extensions>=4.6.0
2026-02-22 00:43:10.894272 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2026-02-22 00:43:10.912870 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 3.0 MB/s eta 0:00:00
2026-02-22 00:43:11.030377 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2026-02-22 00:43:12.103025 | 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-02-22 00:43:12.116273 | controller |
2026-02-22 00:43:12.369879 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-02-22 00:43:12.369926 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-02-22 00:43:12.866329 | controller | ok: Runtime: 0:00:03.281375
2026-02-22 00:43:12.873823 | 
2026-02-22 00:43:12.873941 | TASK [Update pip]
2026-02-22 00:43:15.134909 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2026-02-22 00:43:15.512644 | controller | Collecting pip
2026-02-22 00:43:15.601000 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2026-02-22 00:43:15.703298 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 20.0 MB/s eta 0:00:00
2026-02-22 00:43:15.866663 | controller | Installing collected packages: pip
2026-02-22 00:43:15.867052 | controller |   Attempting uninstall: pip
2026-02-22 00:43:15.868134 | controller |     Found existing installation: pip 22.2.2
2026-02-22 00:43:16.293939 | controller |     Uninstalling pip-22.2.2:
2026-02-22 00:43:16.362368 | controller |       Successfully uninstalled pip-22.2.2
2026-02-22 00:43:19.172594 | controller | Successfully installed pip-25.0.1
2026-02-22 00:43:19.778826 | controller | ok: Runtime: 0:00:05.565182
2026-02-22 00:43:19.800212 | 
2026-02-22 00:43:19.802122 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-02-22 00:43:21.008944 | controller | changed
2026-02-22 00:43:21.015816 | 
2026-02-22 00:43:21.015914 | TASK [Install ansible into virtualenv]
2026-02-22 00:43:22.890719 | controller | Processing ./src/github.com/ansible/ansible
2026-02-22 00:43:22.896058 | controller |   Preparing metadata (setup.py): started
2026-02-22 00:43:24.945236 | controller |   Preparing metadata (setup.py): finished with status 'done'
2026-02-22 00:43:25.011972 | controller | Collecting jinja2 (from ansible==2.9.27.post0)
2026-02-22 00:43:25.031357 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2026-02-22 00:43:25.127437 | controller | Collecting PyYAML (from ansible==2.9.27.post0)
2026-02-22 00:43:25.135021 | 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-02-22 00:43:25.633084 | controller | Collecting cryptography (from ansible==2.9.27.post0)
2026-02-22 00:43:25.638939 | controller |   Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2026-02-22 00:43:25.933572 | controller | Collecting cffi>=1.14 (from cryptography->ansible==2.9.27.post0)
2026-02-22 00:43:25.940759 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2026-02-22 00:43:25.957270 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.8/site-packages (from cryptography->ansible==2.9.27.post0) (4.13.2)
2026-02-22 00:43:26.084746 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible==2.9.27.post0)
2026-02-22 00:43:26.091468 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2026-02-22 00:43:26.133570 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible==2.9.27.post0)
2026-02-22 00:43:26.138670 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2026-02-22 00:43:26.161914 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2026-02-22 00:43:26.233023 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 71.9 MB/s eta 0:00:00
2026-02-22 00:43:26.237893 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2026-02-22 00:43:26.260053 | 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-02-22 00:43:26.293694 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 25.3 MB/s eta 0:00:00
2026-02-22 00:43:26.303207 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2026-02-22 00:43:26.324129 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2026-02-22 00:43:26.341858 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2026-02-22 00:43:26.377631 | controller | Building wheels for collected packages: ansible
2026-02-22 00:43:26.378761 | controller |   Building wheel for ansible (setup.py): started
2026-02-22 00:43:37.807158 | controller |   Building wheel for ansible (setup.py): finished with status 'done'
2026-02-22 00:43:37.831492 | controller |   Created wheel for ansible: filename=ansible-2.9.27.post0-py3-none-any.whl size=16207514 sha256=f50cb7c45d5c682e6b24696f3fa4eba2b0f4907a61c4eaf577ea40229aec94be
2026-02-22 00:43:37.831888 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-ch7gg5mc/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2026-02-22 00:43:37.922700 | controller | Successfully built ansible
2026-02-22 00:43:38.127458 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible
2026-02-22 00:43:53.955252 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-2.9.27.post0 cffi-1.17.1 cryptography-46.0.5 jinja2-3.1.6 pycparser-2.23
2026-02-22 00:43:54.428710 | controller | ok: Runtime: 0:00:32.159133
2026-02-22 00:43:54.442570 | 
2026-02-22 00:43:54.442826 | TASK [Setup download-artifact-fork role]
2026-02-22 00:43:54.473531 | controller | ok
2026-02-22 00:43:54.507840 | 
2026-02-22 00:43:54.507947 | TASK [download-artifact-fork : Parse inventory response]
2026-02-22 00:43:54.564834 | controller | ok
2026-02-22 00:43:54.579422 | 
2026-02-22 00:43:54.579624 | TASK [download-artifact-fork : Fetch info from Zuul API]
2026-02-22 00:43:54.617155 | controller | skipping: Conditional result was False
2026-02-22 00:43:54.627096 | 
2026-02-22 00:43:54.627225 | TASK [download-artifact-fork : Parse build response]
2026-02-22 00:43:54.662117 | controller | skipping: Conditional result was False
2026-02-22 00:43:54.672837 | 
2026-02-22 00:43:54.673152 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2026-02-22 00:43:55.532896 | controller | changed
2026-02-22 00:43:55.543505 | 
2026-02-22 00:43:55.543655 | LOOP [download-artifact-fork : Download archive by type]
2026-02-22 00:43:55.569674 | controller | skipping: Conditional result was False
2026-02-22 00:43:55.570052 | 
2026-02-22 00:43:57.610781 | controller | changed: OK (20208 bytes)
2026-02-22 00:43:57.704054 | 
2026-02-22 00:43:57.704219 | TASK [deploy-artifacts : define __collections]
2026-02-22 00:43:57.738324 | controller | ok
2026-02-22 00:43:57.747124 | 
2026-02-22 00:43:57.747264 | LOOP [deploy-artifacts : Create list of collections]
2026-02-22 00:43:57.785223 | controller | skipping: Conditional result was False
2026-02-22 00:43:57.802451 | controller | ok:
2026-02-22 00:43:57.802572 | controller | {
2026-02-22 00:43:57.802606 | controller |   "branch": "main",
2026-02-22 00:43:57.802634 | controller |   "job": "build-ansible-collection",
2026-02-22 00:43:57.802661 | controller |   "metadata": {
2026-02-22 00:43:57.802686 | controller |     "type": "ansible_collection",
2026-02-22 00:43:57.802711 | controller |     "version": "5.0.0"
2026-02-22 00:43:57.802772 | controller |   },
2026-02-22 00:43:57.802798 | controller |   "name": "ansible.network",
2026-02-22 00:43:57.802822 | controller |   "project": "ansible-collections/ansible.network",
2026-02-22 00:43:57.802847 | controller |   "url": "https://5e28ed2b492a80c5e0c8-d66ebbecf8e3babb6214bb83db5d634d.ssl.cf5.rackcdn.com/ansible/8eb4ea3b8c1b41dabde0210903611d8d/artifacts/ansible-network-5.0.0.tar.gz"
2026-02-22 00:43:57.802879 | controller | }
2026-02-22 00:43:57.812913 | 
2026-02-22 00:43:57.813052 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2026-02-22 00:43:59.113534 | controller | Process install dependency map
2026-02-22 00:44:38.084744 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/Starting collection install process
2026-02-22 00:44:38.185644 | controller | |Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2026-02-22 00:44:38.289110 | controller | Installing 'ansible.netcommon:8.4.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-02-22 00:44:39.599872 | controller | /-\|/-\|/-\|/-Installing 'ansible.utils:6.0.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-02-22 00:44:41.510628 | controller | \|/-\|/-\|/-\|/-\|/Installing 'arista.eos:12.0.1' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2026-02-22 00:44:44.125285 | controller | -\|/-\|/-\|/-\|/-\|/-\|/-\Installing 'cisco.ios:11.3.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2026-02-22 00:44:47.098750 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|/-\|Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2026-02-22 00:44:50.035260 | controller | /-\|/-\|/-\|/-\|/-\|/-\|/-\|/Installing 'cisco.nxos:11.1.3' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2026-02-22 00:44:54.310197 | controller | -\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2026-02-22 00:44:57.214099 | controller | ok: Runtime: 0:00:58.188481
2026-02-22 00:44:57.243343 | 
2026-02-22 00:44:57.243615 | TASK [podman may need iptables kernel modules]
2026-02-22 00:44:58.622483 | controller | ok: Runtime: 0:00:00.011497
2026-02-22 00:44:58.673839 | 
2026-02-22 00:44:58.674043 | PLAY RECAP
2026-02-22 00:44:58.674145 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2026-02-22 00:44:58.674207 | 
2026-02-22 00:44:58.850146 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-02-22 00:44:58.852993 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-02-22 00:44:59.512624 | 
2026-02-22 00:44:59.512808 | PLAY [controller]
2026-02-22 00:44:59.536585 | 
2026-02-22 00:44:59.536722 | TASK [Copy the galaxy.yml on the controller]
2026-02-22 00:45:03.242566 | controller | changed
2026-02-22 00:45:03.248572 | 
2026-02-22 00:45:03.248647 | TASK [Load information from galaxy.yml]
2026-02-22 00:45:03.292143 | controller | ok
2026-02-22 00:45:03.297866 | 
2026-02-22 00:45:03.297929 | LOOP [Enable FIPS mode]
2026-02-22 00:45:03.320992 | 
2026-02-22 00:45:03.321164 | LOOP [ansible-test : Include branch specific variables.]
2026-02-22 00:45:03.353256 | controller | ok: "/var/lib/zuul/builds/86f441b118fe4a368c3476d0c2e82540/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2026-02-22 00:45:03.353474 | 
2026-02-22 00:45:03.366282 | 
2026-02-22 00:45:03.366415 | TASK [ansible-test : Setup --skip-tags for test_options]
2026-02-22 00:45:03.410472 | controller | ok
2026-02-22 00:45:03.418140 | 
2026-02-22 00:45:03.418258 | TASK [ansible-test : Enable --inventory for network-integration]
2026-02-22 00:45:03.444144 | controller | skipping: Conditional result was False
2026-02-22 00:45:03.459290 | 
2026-02-22 00:45:03.459446 | TASK [ansible-test : Enable --retry-on-error]
2026-02-22 00:45:03.486717 | controller | skipping: Conditional result was False
2026-02-22 00:45:03.500375 | 
2026-02-22 00:45:03.500522 | TASK [ansible-test : Enable --continue-on-error]
2026-02-22 00:45:03.548632 | controller | ok
2026-02-22 00:45:03.561173 | 
2026-02-22 00:45:03.561285 | TASK [ansible-test : Enable --requirements]
2026-02-22 00:45:03.581936 | controller | skipping: Conditional result was False
2026-02-22 00:45:03.596696 | 
2026-02-22 00:45:03.596881 | TASK [ansible-test : Adjust options for unit tests]
2026-02-22 00:45:03.624195 | controller | skipping: Conditional result was False
2026-02-22 00:45:03.638102 | 
2026-02-22 00:45:03.638245 | TASK [ansible-test : Install requirements with unit tests]
2026-02-22 00:45:03.738672 | controller | ok
2026-02-22 00:45:03.747202 | 
2026-02-22 00:45:03.747298 | TASK [ansible-test : Set --skip-test]
2026-02-22 00:45:03.816014 | controller | skipping: Conditional result was False
2026-02-22 00:45:03.830501 | 
2026-02-22 00:45:03.830680 | TASK [ansible-test : Reduce verbosity]
2026-02-22 00:45:03.882577 | controller | ok
2026-02-22 00:45:03.895298 | 
2026-02-22 00:45:03.895491 | TASK [ansible-test : Setup --python option]
2026-02-22 00:45:03.926246 | controller | skipping: Conditional result was False
2026-02-22 00:45:03.936837 | 
2026-02-22 00:45:03.936954 | TASK [ansible-test : Setup --docker option]
2026-02-22 00:45:03.973336 | controller | ok
2026-02-22 00:45:03.983810 | 
2026-02-22 00:45:03.983932 | TASK [ansible-test : check if MANIFEST.json exists]
2026-02-22 00:45:05.095663 | controller | ok
2026-02-22 00:45:05.101631 | 
2026-02-22 00:45:05.101699 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2026-02-22 00:45:05.127179 | controller | skipping: Conditional result was False
2026-02-22 00:45:05.135224 | 
2026-02-22 00:45:05.135322 | TASK [ansible-test : Load information from MANIFEST.json]
2026-02-22 00:45:05.167153 | controller | skipping: Conditional result was False
2026-02-22 00:45:05.176566 | 
2026-02-22 00:45:05.176656 | TASK [ansible-test : Setup location of project for integration tests]
2026-02-22 00:45:05.221048 | controller | skipping: Conditional result was False
2026-02-22 00:45:05.228173 | 
2026-02-22 00:45:05.228278 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2026-02-22 00:45:06.563619 | controller | ok
2026-02-22 00:45:06.583816 | 
2026-02-22 00:45:06.583989 | TASK [ansible-test : Load information from galaxy.yml]
2026-02-22 00:45:06.628069 | controller | ok
2026-02-22 00:45:06.634059 | 
2026-02-22 00:45:06.634128 | TASK [ansible-test : Setup location of project for integration tests]
2026-02-22 00:45:06.664879 | controller | ok
2026-02-22 00:45:06.672656 | 
2026-02-22 00:45:06.672753 | TASK [ansible-test : Setup minimum test requirements]
2026-02-22 00:45:06.702345 | controller | ok
2026-02-22 00:45:06.708294 | 
2026-02-22 00:45:06.708365 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2026-02-22 00:45:07.688124 | controller | ok
2026-02-22 00:45:07.702464 | 
2026-02-22 00:45:07.702599 | TASK [ansible-test : Install python requirements]
2026-02-22 00:45:09.318376 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-02-22 00:45:09.336245 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2026-02-22 00:45:09.386842 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-02-22 00:45:09.392766 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2026-02-22 00:45:09.406395 | 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-02-22 00:45:09.414658 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2026-02-22 00:45:09.431468 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2026-02-22 00:45:09.503723 | controller | Installing collected packages: pathspec, yamllint
2026-02-22 00:45:09.594122 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2026-02-22 00:45:10.074802 | controller | ok: Runtime: 0:00:01.114317
2026-02-22 00:45:10.082826 | 
2026-02-22 00:45:10.082927 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2026-02-22 00:45:10.920605 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2026-02-22 00:45:11.440404 | controller | ERROR
2026-02-22 00:45:11.440875 | controller | {
2026-02-22 00:45:11.440951 | controller |   "delta": "0:00:00.018675",
2026-02-22 00:45:11.440995 | controller |   "end": "2026-02-22 00:45:10.923681",
2026-02-22 00:45:11.441220 | controller |   "msg": "non-zero return code",
2026-02-22 00:45:11.441255 | controller |   "rc": 1,
2026-02-22 00:45:11.441306 | controller |   "start": "2026-02-22 00:45:10.905006"
2026-02-22 00:45:11.441359 | controller | }
2026-02-22 00:45:11.441423 | controller | ERROR: Ignoring Errors
2026-02-22 00:45:11.455116 | 
2026-02-22 00:45:11.455258 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2026-02-22 00:45:11.833881 | controller | skipping: Conditional result was False
2026-02-22 00:45:11.841399 | 
2026-02-22 00:45:11.841474 | TASK [ansible-test : Enable persistent connection logging]
2026-02-22 00:45:13.025946 | controller | changed: section and option added
2026-02-22 00:45:13.043161 | 
2026-02-22 00:45:13.043388 | TASK [ansible-test : Set persistent ssh_type value]
2026-02-22 00:45:13.972837 | controller | changed: option added
2026-02-22 00:45:13.986490 | 
2026-02-22 00:45:13.986639 | TASK [ansible-test : Set ansible log path]
2026-02-22 00:45:15.165425 | controller | changed: section and option added
2026-02-22 00:45:15.175268 | 
2026-02-22 00:45:15.175457 | TASK [ansible-test : Identify the changed targets]
2026-02-22 00:45:15.551540 | controller | skipping: Conditional result was False
2026-02-22 00:45:15.561987 | 
2026-02-22 00:45:15.562161 | TASK [ansible-test : set_fact]
2026-02-22 00:45:15.613997 | controller | skipping: Conditional result was False
2026-02-22 00:45:15.621993 | 
2026-02-22 00:45:15.622071 | TASK [ansible-test : Set the targets]
2026-02-22 00:45:15.654877 | controller | ok
2026-02-22 00:45:15.716635 | 
2026-02-22 00:45:15.716825 | TASK [ansible-test : set_fact]
2026-02-22 00:45:15.741507 | controller | skipping: Conditional result was False
2026-02-22 00:45:15.748964 | 
2026-02-22 00:45:15.749069 | TASK [ansible-test : List the targets]
2026-02-22 00:45:16.104456 | controller | skipping: Conditional result was False
2026-02-22 00:45:16.113229 | 
2026-02-22 00:45:16.113336 | TASK [ansible-test : set_fact]
2026-02-22 00:45:16.128062 | controller | skipping: Conditional result was False
2026-02-22 00:45:16.136277 | 
2026-02-22 00:45:16.136391 | TASK [ansible-test : set_fact]
2026-02-22 00:45:16.160715 | controller | skipping: Conditional result was False
2026-02-22 00:45:16.170985 | 
2026-02-22 00:45:16.171119 | LOOP [ansible-test : set_fact]
2026-02-22 00:45:16.223879 | 
2026-02-22 00:45:16.224101 | TASK [ansible-test : set_fact]
2026-02-22 00:45:16.250933 | controller | skipping: Conditional result was False
2026-02-22 00:45:16.259416 | 
2026-02-22 00:45:16.259528 | TASK [ansible-test : debug]
2026-02-22 00:45:16.286032 | controller | skipping: Conditional result was False
2026-02-22 00:45:16.293532 | 
2026-02-22 00:45:16.293618 | TASK [ansible-test : debug]
2026-02-22 00:45:16.339577 | About to run: ansible-test sanity --requirements --docker -v
2026-02-22 00:45:16.345796 | 
2026-02-22 00:45:16.345902 | TASK [ansible-test : Run the test suite]
2026-02-22 00:45:18.342374 | controller | Run command: docker images quay.io/ansible/default-test-container:1.10.1 --format '{{json .}}'
2026-02-22 00:45:18.421211 | controller | Run command: docker pull quay.io/ansible/default-test-container:1.10.1
2026-02-22 00:45:20.120384 | controller | 1.10.1: Pulling from ansible/default-test-container
2026-02-22 00:45:20.122817 | controller | [DEPRECATION NOTICE] Docker Image Format v1, and Docker Image manifest version 2, schema 1 support will be removed in an upcoming release. Suggest the author of quay.io/ansible/default-test-container:1.10.1 to upgrade the image to the OCI Format, or Docker Image manifest v2, schema 2. More information at https://docs.docker.com/go/deprecated-image-specs/
2026-02-22 00:45:20.122865 | controller | 5667fdb72017: Pulling fs layer
2026-02-22 00:45:20.128242 | controller | d83811f270d5: Pulling fs layer
2026-02-22 00:45:20.128276 | controller | ee671aafb583: Pulling fs layer
2026-02-22 00:45:20.128308 | controller | 7fc152dfb3a6: Pulling fs layer
2026-02-22 00:45:20.128481 | controller | 3fb967a6fd03: Pulling fs layer
2026-02-22 00:45:20.128492 | controller | b3e63023ca5f: Pulling fs layer
2026-02-22 00:45:20.128499 | controller | 653cb3e65dbf: Pulling fs layer
2026-02-22 00:45:20.128505 | controller | f4712b586375: Pulling fs layer
2026-02-22 00:45:20.128513 | controller | d696ed7812f8: Pulling fs layer
2026-02-22 00:45:20.128519 | controller | 29bf28764f14: Pulling fs layer
2026-02-22 00:45:20.128526 | controller | 47d7edb3c2b7: Pulling fs layer
2026-02-22 00:45:20.128532 | controller | 07550ad4f6e4: Pulling fs layer
2026-02-22 00:45:20.128538 | controller | 7e79526e64c5: Pulling fs layer
2026-02-22 00:45:20.128545 | controller | 4fffb32882b7: Pulling fs layer
2026-02-22 00:45:20.128551 | controller | fc7a38b06f4e: Pulling fs layer
2026-02-22 00:45:20.128557 | controller | 6d9d912e060e: Pulling fs layer
2026-02-22 00:45:20.128563 | controller | 5dd542dd28d9: Pulling fs layer
2026-02-22 00:45:20.128569 | controller | cef8beb99552: Pulling fs layer
2026-02-22 00:45:20.128575 | controller | bd5557e101ad: Pulling fs layer
2026-02-22 00:45:20.128581 | controller | e057c20fabdd: Pulling fs layer
2026-02-22 00:45:20.128589 | controller | d16059cdae2e: Pulling fs layer
2026-02-22 00:45:20.128595 | controller | 431e42d906a3: Pulling fs layer
2026-02-22 00:45:20.128601 | controller | 7a0fd3b1d7c3: Pulling fs layer
2026-02-22 00:45:20.128607 | controller | aacdb6db0c8c: Pulling fs layer
2026-02-22 00:45:20.128613 | controller | 2e7f04ae8c8b: Pulling fs layer
2026-02-22 00:45:20.128619 | controller | 4cd343027a44: Pulling fs layer
2026-02-22 00:45:20.128625 | controller | 0b4379883f90: Pulling fs layer
2026-02-22 00:45:20.128632 | controller | 3d9d6d9dfc91: Pulling fs layer
2026-02-22 00:45:20.128641 | controller | 57d6314bd293: Pulling fs layer
2026-02-22 00:45:20.128647 | controller | 774036afeb7f: Pulling fs layer
2026-02-22 00:45:20.128653 | controller | 54b10f0e1645: Pulling fs layer
2026-02-22 00:45:20.128659 | controller | 78cf439d9fdb: Pulling fs layer
2026-02-22 00:45:20.128665 | controller | 36e5e20969c7: Pulling fs layer
2026-02-22 00:45:20.128671 | controller | 4b19ac3f17a8: Pulling fs layer
2026-02-22 00:45:20.128677 | controller | 7fc152dfb3a6: Waiting
2026-02-22 00:45:20.128683 | controller | 3fb967a6fd03: Waiting
2026-02-22 00:45:20.128692 | controller | b3e63023ca5f: Waiting
2026-02-22 00:45:20.128698 | controller | 653cb3e65dbf: Waiting
2026-02-22 00:45:20.128704 | controller | f4712b586375: Waiting
2026-02-22 00:45:20.128710 | controller | 7a0fd3b1d7c3: Waiting
2026-02-22 00:45:20.128716 | controller | d696ed7812f8: Waiting
2026-02-22 00:45:20.128722 | controller | 29bf28764f14: Waiting
2026-02-22 00:45:20.128728 | controller | aacdb6db0c8c: Waiting
2026-02-22 00:45:20.128734 | controller | 47d7edb3c2b7: Waiting
2026-02-22 00:45:20.128740 | controller | 2e7f04ae8c8b: Waiting
2026-02-22 00:45:20.128746 | controller | 07550ad4f6e4: Waiting
2026-02-22 00:45:20.128753 | controller | 4cd343027a44: Waiting
2026-02-22 00:45:20.128763 | controller | 7e79526e64c5: Waiting
2026-02-22 00:45:21.119164 | controller | 4fffb32882b7: Waiting
2026-02-22 00:45:21.119215 | controller | fc7a38b06f4e: Waiting
2026-02-22 00:45:21.119223 | controller | 0b4379883f90: Waiting
2026-02-22 00:45:21.119229 | controller | 6d9d912e060e: Waiting
2026-02-22 00:45:21.119235 | controller | 3d9d6d9dfc91: Waiting
2026-02-22 00:45:21.119241 | controller | 5dd542dd28d9: Waiting
2026-02-22 00:45:21.119248 | controller | cef8beb99552: Waiting
2026-02-22 00:45:21.119254 | controller | bd5557e101ad: Waiting
2026-02-22 00:45:21.119260 | controller | 57d6314bd293: Waiting
2026-02-22 00:45:21.119267 | controller | e057c20fabdd: Waiting
2026-02-22 00:45:21.119273 | controller | 774036afeb7f: Waiting
2026-02-22 00:45:21.119279 | controller | d16059cdae2e: Waiting
2026-02-22 00:45:21.119288 | controller | 431e42d906a3: Waiting
2026-02-22 00:45:21.119311 | controller | 54b10f0e1645: Waiting
2026-02-22 00:45:21.119319 | controller | 36e5e20969c7: Waiting
2026-02-22 00:45:21.119325 | controller | 78cf439d9fdb: Waiting
2026-02-22 00:45:21.119331 | controller | 4b19ac3f17a8: Waiting
2026-02-22 00:45:21.119345 | controller | ee671aafb583: Verifying Checksum
2026-02-22 00:45:21.325001 | controller | ee671aafb583: Download complete
2026-02-22 00:45:21.325053 | controller | d83811f270d5: Download complete
2026-02-22 00:45:22.238597 | controller | 7fc152dfb3a6: Verifying Checksum
2026-02-22 00:45:23.252003 | controller | 7fc152dfb3a6: Download complete
2026-02-22 00:45:23.252076 | controller | b3e63023ca5f: Verifying Checksum
2026-02-22 00:45:23.513997 | controller | b3e63023ca5f: Download complete
2026-02-22 00:45:23.514067 | controller | 5667fdb72017: Verifying Checksum
2026-02-22 00:45:24.246091 | controller | 5667fdb72017: Download complete
2026-02-22 00:45:24.246143 | controller | 653cb3e65dbf: Verifying Checksum
2026-02-22 00:45:24.246289 | controller | 653cb3e65dbf: Download complete
2026-02-22 00:45:25.367228 | controller | d696ed7812f8: Verifying Checksum
2026-02-22 00:45:25.367631 | controller | d696ed7812f8: Download complete
2026-02-22 00:45:26.374581 | controller | 29bf28764f14: Verifying Checksum
2026-02-22 00:45:26.815142 | controller | 29bf28764f14: Download complete
2026-02-22 00:45:26.815199 | controller | 3fb967a6fd03: Verifying Checksum
2026-02-22 00:45:27.114322 | controller | 3fb967a6fd03: Download complete
2026-02-22 00:45:27.114377 | controller | 5667fdb72017: Pull complete
2026-02-22 00:45:27.190703 | controller | d83811f270d5: Pull complete
2026-02-22 00:45:27.246764 | controller | ee671aafb583: Pull complete
2026-02-22 00:45:27.289504 | controller | 7fc152dfb3a6: Pull complete
2026-02-22 00:45:27.847942 | controller | 07550ad4f6e4: Verifying Checksum
2026-02-22 00:45:27.848389 | controller | 07550ad4f6e4: Download complete
2026-02-22 00:45:28.058975 | controller | 47d7edb3c2b7: Verifying Checksum
2026-02-22 00:45:28.384214 | controller | 47d7edb3c2b7: Download complete
2026-02-22 00:45:28.384290 | controller | 3fb967a6fd03: Pull complete
2026-02-22 00:45:28.413999 | controller | b3e63023ca5f: Pull complete
2026-02-22 00:45:28.452031 | controller | 653cb3e65dbf: Pull complete
2026-02-22 00:45:28.863711 | controller | 7e79526e64c5: Verifying Checksum
2026-02-22 00:45:29.143488 | controller | 7e79526e64c5: Download complete
2026-02-22 00:45:29.143547 | controller | 4fffb32882b7: Verifying Checksum
2026-02-22 00:45:30.382268 | controller | 4fffb32882b7: Download complete
2026-02-22 00:45:30.382324 | controller | 6d9d912e060e: Verifying Checksum
2026-02-22 00:45:30.516464 | controller | 6d9d912e060e: Download complete
2026-02-22 00:45:30.516518 | controller | fc7a38b06f4e: Verifying Checksum
2026-02-22 00:45:31.448701 | controller | fc7a38b06f4e: Download complete
2026-02-22 00:45:31.448780 | controller | 5dd542dd28d9: Verifying Checksum
2026-02-22 00:45:31.451125 | controller | 5dd542dd28d9: Download complete
2026-02-22 00:45:32.409875 | controller | bd5557e101ad: Verifying Checksum
2026-02-22 00:45:32.409951 | controller | bd5557e101ad: Download complete
2026-02-22 00:45:33.491340 | controller | e057c20fabdd: Verifying Checksum
2026-02-22 00:45:33.491480 | controller | e057c20fabdd: Download complete
2026-02-22 00:45:34.436389 | controller | d16059cdae2e: Verifying Checksum
2026-02-22 00:45:34.436541 | controller | d16059cdae2e: Download complete
2026-02-22 00:45:35.580004 | controller | 431e42d906a3: Verifying Checksum
2026-02-22 00:45:35.580125 | controller | 431e42d906a3: Download complete
2026-02-22 00:45:36.777441 | controller | 7a0fd3b1d7c3: Verifying Checksum
2026-02-22 00:45:36.777572 | controller | 7a0fd3b1d7c3: Download complete
2026-02-22 00:45:38.604157 | controller | aacdb6db0c8c: Verifying Checksum
2026-02-22 00:45:38.604232 | controller | aacdb6db0c8c: Download complete
2026-02-22 00:45:39.544307 | controller | 2e7f04ae8c8b: Download complete
2026-02-22 00:45:40.504401 | controller | 4cd343027a44: Verifying Checksum
2026-02-22 00:45:40.504577 | controller | 4cd343027a44: Download complete
2026-02-22 00:45:41.546903 | controller | 0b4379883f90: Verifying Checksum
2026-02-22 00:45:41.547017 | controller | 0b4379883f90: Download complete
2026-02-22 00:45:42.575970 | controller | 3d9d6d9dfc91: Verifying Checksum
2026-02-22 00:45:54.246923 | controller | 3d9d6d9dfc91: Download complete
2026-02-22 00:45:54.247012 | controller | f4712b586375: Verifying Checksum
2026-02-22 00:45:54.247141 | controller | f4712b586375: Download complete
2026-02-22 00:45:56.767326 | controller | 57d6314bd293: Verifying Checksum
2026-02-22 00:45:56.767413 | controller | 57d6314bd293: Download complete
2026-02-22 00:46:04.974019 | controller | cef8beb99552: Verifying Checksum
2026-02-22 00:46:04.974154 | controller | cef8beb99552: Download complete
2026-02-22 00:46:07.090652 | controller | 54b10f0e1645: Verifying Checksum
2026-02-22 00:46:07.090832 | controller | 54b10f0e1645: Download complete
2026-02-22 00:46:14.348864 | controller | 774036afeb7f: Verifying Checksum
2026-02-22 00:46:14.348944 | controller | 774036afeb7f: Download complete
2026-02-22 00:46:16.894656 | controller | 78cf439d9fdb: Verifying Checksum
2026-02-22 00:46:16.894734 | controller | 78cf439d9fdb: Download complete
2026-02-22 00:46:17.586604 | controller | 36e5e20969c7: Verifying Checksum
2026-02-22 00:46:17.586831 | controller | 36e5e20969c7: Download complete
2026-02-22 00:46:21.253893 | controller | f4712b586375: Pull complete
2026-02-22 00:46:21.380298 | controller | d696ed7812f8: Pull complete
2026-02-22 00:46:21.672611 | controller | 29bf28764f14: Pull complete
2026-02-22 00:46:21.973947 | controller | 47d7edb3c2b7: Pull complete
2026-02-22 00:46:22.188615 | controller | 07550ad4f6e4: Pull complete
2026-02-22 00:46:22.214504 | controller | 7e79526e64c5: Pull complete
2026-02-22 00:46:22.233741 | controller | 4fffb32882b7: Pull complete
2026-02-22 00:46:22.330500 | controller | fc7a38b06f4e: Pull complete
2026-02-22 00:46:22.376409 | controller | 6d9d912e060e: Pull complete
2026-02-22 00:46:22.426833 | controller | 5dd542dd28d9: Pull complete
2026-02-22 00:46:24.612734 | controller | 4b19ac3f17a8: Verifying Checksum
2026-02-22 00:46:24.613008 | controller | 4b19ac3f17a8: Download complete
2026-02-22 00:46:45.130672 | controller | cef8beb99552: Pull complete
2026-02-22 00:46:45.168912 | controller | bd5557e101ad: Pull complete
2026-02-22 00:46:45.201257 | controller | e057c20fabdd: Pull complete
2026-02-22 00:46:45.241478 | controller | d16059cdae2e: Pull complete
2026-02-22 00:46:47.550072 | controller | 431e42d906a3: Pull complete
2026-02-22 00:46:47.623042 | controller | 7a0fd3b1d7c3: Pull complete
2026-02-22 00:46:47.688237 | controller | aacdb6db0c8c: Pull complete
2026-02-22 00:46:47.723167 | controller | 2e7f04ae8c8b: Pull complete
2026-02-22 00:46:47.767613 | controller | 4cd343027a44: Pull complete
2026-02-22 00:46:47.828900 | controller | 0b4379883f90: Pull complete
2026-02-22 00:46:47.869206 | controller | 3d9d6d9dfc91: Pull complete
2026-02-22 00:46:53.946166 | controller | 57d6314bd293: Pull complete
2026-02-22 00:47:17.882543 | controller | 774036afeb7f: Pull complete
2026-02-22 00:47:50.656505 | controller | 54b10f0e1645: Pull complete
2026-02-22 00:48:21.222368 | controller | 78cf439d9fdb: Pull complete
2026-02-22 00:48:50.880588 | controller | 36e5e20969c7: Pull complete
2026-02-22 00:49:22.193361 | controller | 4b19ac3f17a8: Pull complete
2026-02-22 00:49:22.209526 | controller | Digest: sha256:954c4edffb0905df64e23c8611b1c4a62db9af13062f51cad5b658e727238d41
2026-02-22 00:49:22.215740 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:1.10.1
2026-02-22 00:49:22.221667 | controller | quay.io/ansible/default-test-container:1.10.1
2026-02-22 00:49:22.226880 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2026-02-22 00:49:22.259920 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2026-02-22 00:49:23.640320 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2026-02-22 00:49:23.640420 | controller | 04a5f4cda3ee: Pulling fs layer
2026-02-22 00:49:23.640458 | controller | ff496a88c8ed: Pulling fs layer
2026-02-22 00:49:23.640491 | controller | 0ce83f459fe7: Pulling fs layer
2026-02-22 00:49:23.640528 | controller | 2e5170e1f099: Pulling fs layer
2026-02-22 00:49:23.640562 | controller | 7641eb41b08c: Pulling fs layer
2026-02-22 00:49:23.640599 | controller | ad15fa9da398: Pulling fs layer
2026-02-22 00:49:23.640635 | controller | 087d91352424: Pulling fs layer
2026-02-22 00:49:23.642321 | controller | 8b92efd6a100: Pulling fs layer
2026-02-22 00:49:23.642374 | controller | 2e5170e1f099: Waiting
2026-02-22 00:49:23.642570 | controller | 7641eb41b08c: Waiting
2026-02-22 00:49:23.642608 | controller | ad15fa9da398: Waiting
2026-02-22 00:49:23.642667 | controller | 087d91352424: Waiting
2026-02-22 00:49:23.642737 | controller | 8b92efd6a100: Waiting
2026-02-22 00:49:24.322956 | controller | ff496a88c8ed: Verifying Checksum
2026-02-22 00:49:24.323064 | controller | ff496a88c8ed: Download complete
2026-02-22 00:49:24.385364 | controller | 0ce83f459fe7: Download complete
2026-02-22 00:49:25.166046 | controller | 7641eb41b08c: Verifying Checksum
2026-02-22 00:49:25.951692 | controller | 7641eb41b08c: Download complete
2026-02-22 00:49:25.951770 | controller | ad15fa9da398: Verifying Checksum
2026-02-22 00:49:25.951907 | controller | ad15fa9da398: Download complete
2026-02-22 00:49:26.737465 | controller | 087d91352424: Verifying Checksum
2026-02-22 00:49:27.465676 | controller | 087d91352424: Download complete
2026-02-22 00:49:27.465736 | controller | 8b92efd6a100: Verifying Checksum
2026-02-22 00:49:29.072349 | controller | 8b92efd6a100: Download complete
2026-02-22 00:49:29.072424 | controller | 2e5170e1f099: Verifying Checksum
2026-02-22 00:49:30.655120 | controller | 2e5170e1f099: Download complete
2026-02-22 00:49:30.655178 | controller | 04a5f4cda3ee: Verifying Checksum
2026-02-22 00:49:32.874984 | controller | 04a5f4cda3ee: Download complete
2026-02-22 00:49:32.875044 | controller | 04a5f4cda3ee: Pull complete
2026-02-22 00:49:33.450460 | controller | ff496a88c8ed: Pull complete
2026-02-22 00:49:33.488088 | controller | 0ce83f459fe7: Pull complete
2026-02-22 00:49:35.119558 | controller | 2e5170e1f099: Pull complete
2026-02-22 00:49:35.157512 | controller | 7641eb41b08c: Pull complete
2026-02-22 00:49:35.202669 | controller | ad15fa9da398: Pull complete
2026-02-22 00:49:36.328871 | controller | 087d91352424: Pull complete
2026-02-22 00:49:36.366694 | controller | 8b92efd6a100: Pull complete
2026-02-22 00:49:36.397973 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2026-02-22 00:49:36.405877 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2026-02-22 00:49:36.417518 | controller | quay.io/ansible/pypi-test-container:1.0.0
2026-02-22 00:49:36.423018 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2026-02-22 00:49:42.853600 | controller | Run command: docker inspect c0ab714654fcc6ae4dc8968b01b1cbb8666098827ff11fce237efeefad301876
2026-02-22 00:49:43.166088 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2026-02-22 00:49:43.185388 | controller | Including collection: ansible.netcommon (278 files)
2026-02-22 00:49:43.185470 | controller | Including collection: ansible.network (23 files)
2026-02-22 00:49:43.199029 | controller | Including collection: ansible.utils (441 files)
2026-02-22 00:49:43.252280 | controller | Including collection: arista.eos (1031 files)
2026-02-22 00:49:43.316655 | controller | Including collection: cisco.ios (1215 files)
2026-02-22 00:49:43.376311 | controller | Including collection: cisco.iosxr (1044 files)
2026-02-22 00:49:43.472231 | controller | Including collection: cisco.nxos (1687 files)
2026-02-22 00:49:43.535103 | controller | Including collection: junipernetworks.junos (963 files)
2026-02-22 00:49:43.588885 | controller | Creating a payload archive containing 8419 files...
2026-02-22 00:49:46.076072 | controller | Created a 6949426 byte payload archive containing 8419 files in 2 seconds.
2026-02-22 00:49:46.081599 | controller | Assuming Docker is available on localhost.
2026-02-22 00:49:46.082404 | 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:1.10.1
2026-02-22 00:49:46.761920 | controller | Run command: docker exec -i 0c3716b2f0024610b5fdb39cd5e581356d9956e0a277430d2df19cfa1174bd19 dd of=/root/docker.sh bs=65536
2026-02-22 00:49:46.872736 | controller | Run command: docker exec 0c3716b2f0024610b5fdb39cd5e581356d9956e0a277430d2df19cfa1174bd19 /bin/bash /root/docker.sh
2026-02-22 00:49:46.988515 | controller | Run command: docker exec -i 0c3716b2f0024610b5fdb39cd5e581356d9956e0a277430d2df19cfa1174bd19 dd of=/root/ansible.tgz bs=65536
2026-02-22 00:49:47.129108 | controller | Run command: docker exec 0c3716b2f0024610b5fdb39cd5e581356d9956e0a277430d2df19cfa1174bd19 mkdir /root/ansible
2026-02-22 00:49:47.228046 | controller | Run command: docker exec 0c3716b2f0024610b5fdb39cd5e581356d9956e0a277430d2df19cfa1174bd19 tar oxzf /root/ansible.tgz -C /root/ansible
2026-02-22 00:49:48.617662 | controller | Run command: docker exec 0c3716b2f0024610b5fdb39cd5e581356d9956e0a277430d2df19cfa1174bd19 /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata metadata-srgdry_i.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2026-02-22 00:49:49.256679 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2026-02-22 00:49:49.257294 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2026-02-22 00:49:49.257699 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2026-02-22 00:49:49.260340 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-02-22 00:49:49.363931 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2026-02-22 00:49:49.365980 | controller | Read 0 sanity test ignore line(s) for Ansible 2.9 from: tests/sanity/ignore-2.9.txt
2026-02-22 00:49:49.375634 | controller | No tests applicable.
2026-02-22 00:49:49.375838 | controller | Running sanity test 'ansible-doc' with Python 3.6
2026-02-22 00:49:49.376113 | controller | No tests applicable.
2026-02-22 00:49:49.376495 | controller | Run command: /usr/bin/python2.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-02-22 00:49:49.465303 | controller | Running sanity test 'compile' with Python 2.6
2026-02-22 00:49:49.467231 | controller | No tests applicable.
2026-02-22 00:49:49.710268 | controller | Run command: /usr/bin/python2.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-02-22 00:49:49.710332 | controller | Running sanity test 'compile' with Python 2.7
2026-02-22 00:49:49.710697 | controller | No tests applicable.
2026-02-22 00:49:49.710718 | controller | Run command: /usr/bin/python3.5 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-02-22 00:49:49.869640 | controller | Running sanity test 'compile' with Python 3.5
2026-02-22 00:49:49.870110 | controller | No tests applicable.
2026-02-22 00:49:49.870213 | controller | Running sanity test 'compile' with Python 3.6
2026-02-22 00:49:49.870483 | controller | No tests applicable.
2026-02-22 00:49:49.870921 | controller | Run command: /usr/bin/python3.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-02-22 00:49:50.000959 | controller | Running sanity test 'compile' with Python 3.7
2026-02-22 00:49:50.137931 | controller | No tests applicable.
2026-02-22 00:49:50.137988 | controller | Run command: /usr/bin/python3.8 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2026-02-22 00:49:50.138004 | controller | Running sanity test 'compile' with Python 3.8
2026-02-22 00:49:50.138166 | controller | No tests applicable.
2026-02-22 00:49:50.138179 | controller | Running sanity test 'empty-init' with Python 3.6
2026-02-22 00:49:50.138186 | controller | No tests applicable.
2026-02-22 00:49:50.138192 | controller | Running sanity test 'future-import-boilerplate' with Python 3.6
2026-02-22 00:49:50.138198 | controller | No tests applicable.
2026-02-22 00:49:50.138208 | controller | Running sanity test 'ignores'
2026-02-22 00:49:50.139504 | controller | Run command: /usr/bin/python3.6 -m pip.__main__ install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt
2026-02-22 00:49:51.002047 | controller | Ignoring packaging: markers 'python_version < "3.6"' don't match your environment
2026-02-22 00:49:51.007831 | controller | Ignoring coverage: markers 'python_version > "3.7"' don't match your environment
2026-02-22 00:49:51.009329 | controller | Ignoring cryptography: markers 'python_version < "2.7"' don't match your environment
2026-02-22 00:49:51.012550 | controller | Ignoring deepdiff: markers 'python_version < "3"' don't match your environment
2026-02-22 00:49:51.015261 | controller | Ignoring jinja2: markers 'python_version < "2.7"' don't match your environment
2026-02-22 00:49:51.016779 | controller | Ignoring urllib3: markers 'python_version < "2.7"' don't match your environment
2026-02-22 00:49:51.023170 | controller | Ignoring sphinx: markers 'python_version < "2.7"' don't match your environment
2026-02-22 00:49:51.028935 | controller | Ignoring wheel: markers 'python_version < "2.7"' don't match your environment
2026-02-22 00:49:51.030401 | controller | Ignoring yamllint: markers 'python_version < "2.7"' don't match your environment
2026-02-22 00:49:51.035014 | controller | Ignoring paramiko: markers 'python_version < "2.7"' don't match your environment
2026-02-22 00:49:51.039247 | controller | Ignoring pytest: markers 'python_version < "2.7"' don't match your environment
2026-02-22 00:49:51.040861 | controller | Ignoring pytest: markers 'python_version == "2.7"' don't match your environment
2026-02-22 00:49:51.044380 | controller | Ignoring pytest-forked: markers 'python_version < "2.7"' don't match your environment
2026-02-22 00:49:51.048303 | controller | Ignoring requests: markers 'python_version < "2.7"' don't match your environment
2026-02-22 00:49:51.055339 | controller | Ignoring virtualenv: markers 'python_version < "2.7"' don't match your environment
2026-02-22 00:49:51.058189 | controller | Ignoring pathspec: markers 'python_version < "2.7"' don't match your environment
2026-02-22 00:49:51.059687 | controller | Ignoring pyopenssl: markers 'python_version < "2.7"' don't match your environment
2026-02-22 00:49:51.064021 | controller | Ignoring pyyaml: markers 'python_version < "2.7"' don't match your environment
2026-02-22 00:49:51.066763 | controller | Ignoring pycparser: markers 'python_version < "2.7"' don't match your environment
2026-02-22 00:49:51.067927 | controller | Ignoring xmltodict: markers 'python_version < "2.7"' don't match your environment
2026-02-22 00:49:51.072559 | controller | Ignoring lxml: markers 'python_version < "2.7"' don't match your environment
2026-02-22 00:49:51.073859 | controller | Ignoring pyvmomi: markers 'python_version < "2.7"' don't match your environment
2026-02-22 00:49:51.076481 | controller | Ignoring MarkupSafe: markers 'python_version < "3.6"' don't match your environment
2026-02-22 00:49:51.082150 | controller | Ignoring setuptools: markers 'python_version <= "2.7"' don't match your environment
2026-02-22 00:49:51.087906 | controller | Ignoring websocket-client: markers 'python_version < "3"' don't match your environment
2026-02-22 00:49:51.089243 | controller | Ignoring certifi: markers 'python_version < "3"' don't match your environment
2026-02-22 00:49:51.109623 | controller | Looking in indexes: http://172.17.0.2:3141/root/pypi/+simple/
2026-02-22 00:49:51.110917 | controller | Requirement already satisfied: cryptography<3.4 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (2.7)
2026-02-22 00:49:51.141128 | controller | Requirement already satisfied: rstcheck==3.3.1 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 12)) (3.3.1)
2026-02-22 00:49:51.144987 | controller | Requirement already satisfied: voluptuous>=0.11.0 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 30)) (0.11.7)
2026-02-22 00:49:51.149506 | controller | Requirement already satisfied: cffi!=1.14.4 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 49)) (1.13.0)
2026-02-22 00:49:51.152141 | controller | Requirement already satisfied: pylint==2.3.1 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 58)) (2.3.1)
2026-02-22 00:49:51.162450 | controller | Requirement already satisfied: jinja2 in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 2)) (2.10.3)
2026-02-22 00:49:51.165834 | controller | Requirement already satisfied: pycodestyle in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 3)) (2.5.0)
2026-02-22 00:49:51.171281 | controller | Requirement already satisfied: pyyaml in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 5)) (5.1.2)
2026-02-22 00:49:51.173438 | controller | Requirement already satisfied: virtualenv in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 7)) (16.7.5)
2026-02-22 00:49:51.195956 | controller | Requirement already satisfied: yamllint in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 9)) (1.18.0)
2026-02-22 00:49:51.202464 | controller | Requirement already satisfied: six>=1.4.1 in /usr/local/lib/python3.6/dist-packages (from cryptography<3.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (1.12.0)
2026-02-22 00:49:51.204742 | controller | Requirement already satisfied: asn1crypto>=0.21.0 in /usr/local/lib/python3.6/dist-packages (from cryptography<3.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (1.1.0)
2026-02-22 00:49:51.210751 | controller | Requirement already satisfied: docutils>=0.7 in /usr/local/lib/python3.6/dist-packages (from rstcheck==3.3.1->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 12)) (0.15.2)
2026-02-22 00:49:51.216513 | controller | Requirement already satisfied: pycparser in /usr/local/lib/python3.6/dist-packages (from cffi!=1.14.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 49)) (2.19)
2026-02-22 00:49:51.220957 | controller | Requirement already satisfied: mccabe==0.6.1 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 57)) (0.6.1)
2026-02-22 00:49:51.224080 | controller | Collecting isort==4.3.16 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 55))
2026-02-22 00:50:06.241071 | 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/isort/
2026-02-22 00:50:21.756700 | 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/isort/
2026-02-22 00:50:37.773874 | 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/isort/
2026-02-22 00:50:54.800121 | 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/isort/
2026-02-22 00:51:13.818494 | 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/isort/
2026-02-22 00:51:28.831489 | controller |   ERROR: Could not find a version that satisfies the requirement isort==4.3.16 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 55)) (from versions: none)
2026-02-22 00:51:28.832380 | controller | ERROR: No matching distribution found for isort==4.3.16 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 55))
2026-02-22 00:51:28.954626 | controller | ERROR: Command "/usr/bin/python3.6 -m pip.__main__ install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt" returned exit status 1.
2026-02-22 00:51:28.954744 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2026-02-22 00:51:28.998872 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2026-02-22 00:51:28.998922 | controller | Removing custom PyPI hosts entries: /etc/hosts
2026-02-22 00:51:28.998944 | controller | Run command: docker exec 0c3716b2f0024610b5fdb39cd5e581356d9956e0a277430d2df19cfa1174bd19 tar czf /root/output.tgz --exclude .tmp -C /root/ansible/ansible_collections/ansible/network/tests output
2026-02-22 00:51:29.088671 | controller | Run command: docker exec -i 0c3716b2f0024610b5fdb39cd5e581356d9956e0a277430d2df19cfa1174bd19 dd if=/root/output.tgz bs=65536
2026-02-22 00:51:29.173907 | controller | Run command: tar oxzf /tmp/ansible-result-trts7lf3.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2026-02-22 00:51:29.198592 | controller | Run command: docker rm -f c0ab714654fcc6ae4dc8968b01b1cbb8666098827ff11fce237efeefad301876
2026-02-22 00:51:29.515879 | controller | Run command: docker rm -f 0c3716b2f0024610b5fdb39cd5e581356d9956e0a277430d2df19cfa1174bd19
2026-02-22 00:51:30.416003 | controller | ERROR: Command "docker exec 0c3716b2f0024610b5fdb39cd5e581356d9956e0a277430d2df19cfa1174bd19 /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata metadata-srgdry_i.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2026-02-22 00:51:30.991535 | controller | ERROR
2026-02-22 00:51:30.991895 | controller | {
2026-02-22 00:51:30.991968 | controller |   "delta": "0:06:12.824163",
2026-02-22 00:51:30.992012 | controller |   "end": "2026-02-22 00:51:30.505300",
2026-02-22 00:51:30.992051 | controller |   "msg": "non-zero return code",
2026-02-22 00:51:30.992087 | controller |   "rc": 1,
2026-02-22 00:51:30.992126 | controller |   "start": "2026-02-22 00:45:17.681137"
2026-02-22 00:51:30.992160 | controller | }
failure
2026-02-22 00:51:31.000943 | 
2026-02-22 00:51:31.001067 | PLAY RECAP
2026-02-22 00:51:31.001151 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2026-02-22 00:51:31.001195 | 
2026-02-22 00:51:31.131040 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-02-22 00:51:31.131920 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-02-22 00:51:31.689133 | 
2026-02-22 00:51:31.689242 | PLAY [all]
2026-02-22 00:51:31.710095 | 
2026-02-22 00:51:31.710210 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-02-22 00:51:34.745926 | controller | changed: non-zero return code
2026-02-22 00:51:34.758643 | 
2026-02-22 00:51:34.758889 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-02-22 00:51:34.786792 | controller | skipping: Conditional result was False
2026-02-22 00:51:34.800316 | 
2026-02-22 00:51:34.800469 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-02-22 00:51:34.842198 | 
2026-02-22 00:51:34.842482 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-02-22 00:51:34.883133 | 
2026-02-22 00:51:34.883412 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-02-22 00:51:34.911060 | controller | skipping: Conditional result was False
2026-02-22 00:51:34.924178 | 
2026-02-22 00:51:34.924325 | LOOP [fetch-subunit-output : Generate subunit file]
2026-02-22 00:51:34.965202 | 
2026-02-22 00:51:34.965483 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-02-22 00:51:34.993278 | controller | skipping: Conditional result was False
2026-02-22 00:51:35.009651 | 
2026-02-22 00:51:35.009915 | TASK [fetch-subunit-output : Remove the temporary file]
2026-02-22 00:51:35.027034 | controller | skipping: Conditional result was False
2026-02-22 00:51:35.040160 | 
2026-02-22 00:51:35.040310 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-02-22 00:51:35.067656 | controller | skipping: Conditional result was False
2026-02-22 00:51:35.115941 | 
2026-02-22 00:51:35.116064 | PLAY RECAP
2026-02-22 00:51:35.116125 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-02-22 00:51:35.116154 | 
2026-02-22 00:51:35.220373 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-02-22 00:51:35.221238 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-02-22 00:51:35.818281 | 
2026-02-22 00:51:35.818430 | PLAY [all:!appliance*]
2026-02-22 00:51:35.841210 | 
2026-02-22 00:51:35.841317 | TASK [unregister the node]
2026-02-22 00:51:36.197163 | controller | skipping: Conditional result was False
2026-02-22 00:51:36.210285 | 
2026-02-22 00:51:36.210477 | TASK [include_role : fetch-output]
2026-02-22 00:51:36.260744 | controller | ok
2026-02-22 00:51:36.300668 | 
2026-02-22 00:51:36.300863 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-22 00:51:36.378369 | controller | skipping: Conditional result was False
2026-02-22 00:51:36.388060 | 
2026-02-22 00:51:36.388229 | TASK [fetch-output : Set log path for single node]
2026-02-22 00:51:36.431944 | controller | ok
2026-02-22 00:51:36.439861 | 
2026-02-22 00:51:36.439966 | LOOP [fetch-output : Ensure local output dirs]
2026-02-22 00:51:36.892084 | controller -> localhost | ok: "/var/lib/zuul/builds/86f441b118fe4a368c3476d0c2e82540/work/logs"
2026-02-22 00:51:37.133425 | controller -> localhost | changed: "/var/lib/zuul/builds/86f441b118fe4a368c3476d0c2e82540/work/artifacts"
2026-02-22 00:51:37.412355 | controller -> localhost | changed: "/var/lib/zuul/builds/86f441b118fe4a368c3476d0c2e82540/work/docs"
2026-02-22 00:51:37.437176 | 
2026-02-22 00:51:37.437349 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-22 00:51:39.742590 | controller | changed:
2026-02-22 00:51:39.742828 | controller | .d..t...... ./
2026-02-22 00:51:39.742864 | controller | cd+++++++++ controller/
2026-02-22 00:51:39.742903 | controller | changed: All items complete
2026-02-22 00:51:39.742924 | 
2026-02-22 00:51:41.852367 | controller | changed: .d..t...... ./
2026-02-22 00:51:43.930250 | controller | changed: .d..t...... ./
2026-02-22 00:51:43.958207 | 
2026-02-22 00:51:43.958402 | TASK [include_role : fetch-output-openshift]
2026-02-22 00:51:43.976139 | controller | skipping: Conditional result was False
2026-02-22 00:51:43.992934 | 
2026-02-22 00:51:43.993117 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-22 00:51:44.034201 | controller | skipping: Conditional result was False
2026-02-22 00:51:44.046127 | controller | skipping: Conditional result was False
2026-02-22 00:51:44.106100 | 
2026-02-22 00:51:44.106228 | PLAY [localhost]
2026-02-22 00:51:44.123921 | 
2026-02-22 00:51:44.124008 | TASK [Run Zuul manifest role]
2026-02-22 00:51:44.144815 | localhost | ok
2026-02-22 00:51:44.161818 | 
2026-02-22 00:51:44.161908 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-22 00:51:44.595056 | localhost | changed
2026-02-22 00:51:44.604871 | 
2026-02-22 00:51:44.605028 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-22 00:51:44.651092 | localhost | ok
2026-02-22 00:51:44.669455 | 
2026-02-22 00:51:44.669577 | TASK [Set zuul-log-path fact]
2026-02-22 00:51:44.725606 | localhost | ok
2026-02-22 00:51:44.742425 | 
2026-02-22 00:51:44.742522 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-22 00:51:44.782921 | localhost | ok
2026-02-22 00:51:44.793452 | 
2026-02-22 00:51:44.793538 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)]
2026-02-22 00:51:44.839133 | localhost | ok:
2026-02-22 00:51:44.839405 | localhost | {
2026-02-22 00:51:44.839459 | localhost |   "auth": {
2026-02-22 00:51:44.839509 | localhost |     "password": "IonWCwcuOFib&&<}wp^:[2G/",
2026-02-22 00:51:44.839548 | localhost |     "username": "9LF91O0RK7JU"
2026-02-22 00:51:44.839585 | localhost |   },
2026-02-22 00:51:44.839620 | localhost |   "profile": "rackspace",
2026-02-22 00:51:44.839655 | localhost |   "region_name": "IAD"
2026-02-22 00:51:44.839691 | localhost | }
2026-02-22 00:51:44.839793 | 
2026-02-22 00:51:44.852456 | 
2026-02-22 00:51:44.852703 | TASK [Run upload-logs-failover role (S3 then Swift)]
2026-02-22 00:51:44.882041 | localhost | Output suppressed because no_log was given
2026-02-22 00:51:44.911426 | 
2026-02-22 00:51:44.911580 | TASK [upload-logs-failover : Initialize control vars]
2026-02-22 00:51:44.942788 | localhost | ok
2026-02-22 00:51:44.948533 | 
2026-02-22 00:51:44.948616 | LOOP [upload-logs-failover : Perform log upload]
2026-02-22 00:51:44.995516 | localhost | Output suppressed because no_log was given
2026-02-22 00:51:45.005034 | localhost | included: /var/lib/zuul/builds/86f441b118fe4a368c3476d0c2e82540/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml
2026-02-22 00:51:45.014011 | 
2026-02-22 00:51:45.014108 | LOOP [upload-logs-failover : Unpack vars for swift upload]
2026-02-22 00:51:45.134510 | localhost | Output suppressed because no_log was given
2026-02-22 00:51:45.147393 | 
2026-02-22 00:51:45.147569 | TASK [Upload logs to swift]
2026-02-22 00:51:45.179093 | localhost | ok
2026-02-22 00:51:45.224702 | 
2026-02-22 00:51:45.224868 | TASK [Set zuul-log-path fact]
2026-02-22 00:51:45.260019 | localhost | skipping: Conditional result was False
2026-02-22 00:51:45.267874 | 
2026-02-22 00:51:45.268021 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-02-22 00:51:45.720689 | localhost -> localhost | ok: Runtime: 0:00:00.008865
2026-02-22 00:51:45.726177 | 
2026-02-22 00:51:45.726240 | TASK [upload-logs-swift : Upload logs to swift]