2026-03-16 00:06:47.970689 | Job console starting...
2026-03-16 00:06:47.983971 | Updating repositories
2026-03-16 00:06:48.504045 | Preparing job workspace
2026-03-16 00:06:53.381714 | Running Ansible setup...
2026-03-16 00:06:57.678855 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-16 00:06:58.305313 | 
2026-03-16 00:06:58.305463 | PLAY [localhost]
2026-03-16 00:06:58.314969 | 
2026-03-16 00:06:58.315097 | TASK [Gathering Facts]
2026-03-16 00:06:59.354840 | localhost | ok
2026-03-16 00:06:59.391971 | 
2026-03-16 00:06:59.392331 | TASK [Setup log path fact]
2026-03-16 00:06:59.414120 | localhost | ok
2026-03-16 00:06:59.434481 | 
2026-03-16 00:06:59.434625 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-16 00:06:59.479822 | localhost | ok
2026-03-16 00:06:59.496602 | 
2026-03-16 00:06:59.496800 | TASK [emit-job-header : Print job information]
2026-03-16 00:06:59.549676 | # Job Information
2026-03-16 00:06:59.549880 | Ansible Version: 2.15.12
2026-03-16 00:06:59.549924 | Job: ansible-test-sanity-docker-stable-2.10
2026-03-16 00:06:59.549956 | Pipeline: periodic
2026-03-16 00:06:59.549986 | Executor: ze01.softwarefactory-project.io
2026-03-16 00:06:59.550050 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-03-16 00:06:59.550092 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/9c6/ansible/9c60709488604920bb23d71f7829947c/
2026-03-16 00:06:59.550123 | Event ID: a65e945ef85543369ffb13b5719da6dd
2026-03-16 00:06:59.555700 | 
2026-03-16 00:06:59.555788 | LOOP [emit-job-header : Print node information]
2026-03-16 00:06:59.682797 | localhost | ok:
2026-03-16 00:06:59.683105 | localhost | # Node Information
2026-03-16 00:06:59.683170 | localhost | Inventory Hostname: controller
2026-03-16 00:06:59.683217 | localhost | Hostname: np0005647953
2026-03-16 00:06:59.683258 | localhost | Username: zuul
2026-03-16 00:06:59.683316 | localhost | Distro: Fedora 37
2026-03-16 00:06:59.683374 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2026-03-16 00:06:59.683415 | localhost | Region: ca-ymq-1
2026-03-16 00:06:59.683451 | localhost | Label: ansible-fedora-37-1vcpu
2026-03-16 00:06:59.683485 | localhost | Product Name: OpenStack Nova
2026-03-16 00:06:59.683521 | localhost | Interface IP: 162.253.55.36
2026-03-16 00:06:59.706271 | 
2026-03-16 00:06:59.706431 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-16 00:07:00.172138 | localhost -> localhost | changed
2026-03-16 00:07:00.178814 | 
2026-03-16 00:07:00.178921 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-16 00:07:01.174528 | localhost -> localhost | changed
2026-03-16 00:07:01.206590 | 
2026-03-16 00:07:01.206707 | PLAY [all:!appliance*]
2026-03-16 00:07:01.234784 | 
2026-03-16 00:07:01.234931 | TASK [include_role : start-zuul-console]
2026-03-16 00:07:01.254774 | controller | ok
2026-03-16 00:07:01.269720 | 
2026-03-16 00:07:01.269880 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-16 00:07:01.684715 | controller | ok
2026-03-16 00:07:01.696481 | 
2026-03-16 00:07:01.696583 | TASK [use-our-mirror : Retrieve the IP address]
2026-03-16 00:07:23.971046 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-03-16 00:07:23.986524 | 
2026-03-16 00:07:23.986665 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-03-16 00:07:24.543902 | controller | skipping: Conditional result was False
2026-03-16 00:07:24.559189 | 
2026-03-16 00:07:24.559368 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-03-16 00:07:24.577140 | controller | skipping: Conditional result was False
2026-03-16 00:07:24.596329 | 
2026-03-16 00:07:24.596575 | TASK [use-our-mirror : Create the podman configuration directory]
2026-03-16 00:07:24.622718 | controller | skipping: Conditional result was False
2026-03-16 00:07:24.634863 | 
2026-03-16 00:07:24.635795 | TASK [use-our-mirror : Copy the podman configuration]
2026-03-16 00:07:24.651807 | controller | skipping: Conditional result was False
2026-03-16 00:07:24.660449 | 
2026-03-16 00:07:24.660573 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-03-16 00:07:24.675124 | controller | skipping: Conditional result was False
2026-03-16 00:07:24.686082 | 
2026-03-16 00:07:24.686259 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-03-16 00:07:24.702546 | controller | skipping: Conditional result was False
2026-03-16 00:07:24.722506 | 
2026-03-16 00:07:24.722661 | TASK [Disable Fedora Modular]
2026-03-16 00:07:25.063902 | controller | changed
2026-03-16 00:07:25.084964 | 
2026-03-16 00:07:25.085362 | TASK [Enable EPEL]
2026-03-16 00:07:25.113698 | controller | skipping: Conditional result was False
2026-03-16 00:07:25.129492 | 
2026-03-16 00:07:25.129661 | TASK [Register the RHEL node]
2026-03-16 00:07:25.694357 | 
2026-03-16 00:07:25.694632 | TASK [Show the subscription-manager status]
2026-03-16 00:07:26.270368 | controller | skipping: Conditional result was False
2026-03-16 00:07:26.278360 | 
2026-03-16 00:07:26.278445 | TASK [Enable EPEL on RHEL]
2026-03-16 00:07:26.817706 | controller | skipping: Conditional result was False
2026-03-16 00:07:26.836073 | 
2026-03-16 00:07:26.836260 | TASK [Install git and tox]
2026-03-16 00:08:30.336885 | controller | changed
2026-03-16 00:08:30.359893 | 
2026-03-16 00:08:30.360040 | TASK [include_role : prepare-workspace]
2026-03-16 00:08:30.382410 | controller | ok
2026-03-16 00:08:30.412254 | 
2026-03-16 00:08:30.412381 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-16 00:08:30.688044 | controller | ok
2026-03-16 00:08:30.698942 | 
2026-03-16 00:08:30.699128 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-16 00:08:46.648930 | controller | Output suppressed because no_log was given
2026-03-16 00:08:46.659710 | 
2026-03-16 00:08:46.659824 | TASK [include_role : prepare-workspace-openshift]
2026-03-16 00:08:46.684593 | controller | skipping: Conditional result was False
2026-03-16 00:08:46.723605 | 
2026-03-16 00:08:46.723721 | PLAY [all:!appliance]
2026-03-16 00:08:46.743289 | 
2026-03-16 00:08:46.743593 | TASK [Run add-build-sshkey role (RSA)]
2026-03-16 00:08:46.785139 | controller | ok
2026-03-16 00:08:46.850295 | 
2026-03-16 00:08:46.850426 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-16 00:08:47.089132 | controller -> localhost | ok
2026-03-16 00:08:47.096530 | 
2026-03-16 00:08:47.096639 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-16 00:08:47.117127 | controller | ok
2026-03-16 00:08:47.131903 | controller | included: /var/lib/zuul/builds/9c60709488604920bb23d71f7829947c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-16 00:08:47.139368 | 
2026-03-16 00:08:47.139578 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-16 00:08:47.724227 | controller -> localhost | Generating public/private rsa key pair.
2026-03-16 00:08:47.724479 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9c60709488604920bb23d71f7829947c/work/9c60709488604920bb23d71f7829947c_id_rsa.
2026-03-16 00:08:47.724509 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9c60709488604920bb23d71f7829947c/work/9c60709488604920bb23d71f7829947c_id_rsa.pub.
2026-03-16 00:08:47.724530 | controller -> localhost | The key fingerprint is:
2026-03-16 00:08:47.724549 | controller -> localhost | SHA256:L+1YGSZ0E7SeqnptEzGEX2Gm9vZKpweHb7Dh6+Tv5fA zuul-build-sshkey
2026-03-16 00:08:47.724567 | controller -> localhost | The key's randomart image is:
2026-03-16 00:08:47.724585 | controller -> localhost | +---[RSA 2048]----+
2026-03-16 00:08:47.724603 | controller -> localhost | |       . .*.     |
2026-03-16 00:08:47.724620 | controller -> localhost | |      . .+.o     |
2026-03-16 00:08:47.724637 | controller -> localhost | |       o+.+      |
2026-03-16 00:08:47.724655 | controller -> localhost | |       o++ o     |
2026-03-16 00:08:47.724671 | controller -> localhost | |        SoB.     |
2026-03-16 00:08:47.724701 | controller -> localhost | |        .B=+.    |
2026-03-16 00:08:47.724725 | controller -> localhost | |       .ooBB+ .  |
2026-03-16 00:08:47.724746 | controller -> localhost | |      ..+Oo++=   |
2026-03-16 00:08:47.724766 | controller -> localhost | |    .o...oB*o E  |
2026-03-16 00:08:47.724787 | controller -> localhost | +----[SHA256]-----+
2026-03-16 00:08:47.724841 | controller -> localhost | ok: Runtime: 0:00:00.136432
2026-03-16 00:08:47.732399 | 
2026-03-16 00:08:47.732529 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-16 00:08:47.763790 | controller | ok
2026-03-16 00:08:47.774480 | controller | included: /var/lib/zuul/builds/9c60709488604920bb23d71f7829947c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-16 00:08:47.784300 | 
2026-03-16 00:08:47.784445 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-16 00:08:47.809444 | controller | skipping: Conditional result was False
2026-03-16 00:08:47.817389 | 
2026-03-16 00:08:47.817521 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-16 00:08:48.210716 | controller | changed
2026-03-16 00:08:48.223506 | 
2026-03-16 00:08:48.223650 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-16 00:08:48.456945 | controller | ok
2026-03-16 00:08:48.471556 | 
2026-03-16 00:08:48.471710 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-16 00:08:49.120827 | controller | changed
2026-03-16 00:08:49.127190 | 
2026-03-16 00:08:49.127273 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-16 00:08:49.752340 | controller | changed
2026-03-16 00:08:49.764840 | 
2026-03-16 00:08:49.764976 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-16 00:08:49.792795 | controller | skipping: Conditional result was False
2026-03-16 00:08:49.809119 | 
2026-03-16 00:08:49.809284 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-16 00:08:50.245327 | controller -> localhost | changed
2026-03-16 00:08:50.267920 | 
2026-03-16 00:08:50.268048 | TASK [add-build-sshkey : Add back temp key]
2026-03-16 00:08:50.558784 | controller -> localhost | Identity added: /var/lib/zuul/builds/9c60709488604920bb23d71f7829947c/work/9c60709488604920bb23d71f7829947c_id_rsa (zuul-build-sshkey)
2026-03-16 00:08:50.559004 | controller -> localhost | ok: Runtime: 0:00:00.013315
2026-03-16 00:08:50.565882 | 
2026-03-16 00:08:50.565990 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-16 00:08:50.906555 | controller | ok
2026-03-16 00:08:50.918644 | 
2026-03-16 00:08:50.918813 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-16 00:08:50.945457 | controller | skipping: Conditional result was False
2026-03-16 00:08:50.957974 | 
2026-03-16 00:08:50.958110 | TASK [Run add-build-sshkey role (ECDSA)]
2026-03-16 00:08:50.978411 | controller | ok
2026-03-16 00:08:50.995899 | 
2026-03-16 00:08:50.996043 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-16 00:08:51.237311 | controller -> localhost | ok
2026-03-16 00:08:51.254687 | 
2026-03-16 00:08:51.254859 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-16 00:08:51.297306 | controller | ok
2026-03-16 00:08:51.322314 | controller | included: /var/lib/zuul/builds/9c60709488604920bb23d71f7829947c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-16 00:08:51.331967 | 
2026-03-16 00:08:51.332102 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-16 00:08:51.618181 | controller -> localhost | Generating public/private ecdsa key pair.
2026-03-16 00:08:51.618505 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9c60709488604920bb23d71f7829947c/work/9c60709488604920bb23d71f7829947c_id_ecdsa.
2026-03-16 00:08:51.618570 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9c60709488604920bb23d71f7829947c/work/9c60709488604920bb23d71f7829947c_id_ecdsa.pub.
2026-03-16 00:08:51.618637 | controller -> localhost | The key fingerprint is:
2026-03-16 00:08:51.618684 | controller -> localhost | SHA256:NNdnr4RuggRrD691ESelGNBP5+k1N7CvjVO4i/X2QBo zuul-build-sshkey
2026-03-16 00:08:51.618730 | controller -> localhost | The key's randomart image is:
2026-03-16 00:08:51.618790 | controller -> localhost | +---[ECDSA 521]---+
2026-03-16 00:08:51.618862 | controller -> localhost | |      .o.   .    |
2026-03-16 00:08:51.618939 | controller -> localhost | |        .o.+..   |
2026-03-16 00:08:51.619010 | controller -> localhost | |      . +o=oo.=  |
2026-03-16 00:08:51.619139 | controller -> localhost | |       + o.+o=oo.|
2026-03-16 00:08:51.619186 | controller -> localhost | |      + S ..E.=oo|
2026-03-16 00:08:51.619227 | controller -> localhost | |     . = . o.* + |
2026-03-16 00:08:51.619269 | controller -> localhost | |        = o +.O  |
2026-03-16 00:08:51.619309 | controller -> localhost | |       o . oo=.+ |
2026-03-16 00:08:51.619350 | controller -> localhost | |      .    . .+.o|
2026-03-16 00:08:51.619390 | controller -> localhost | +----[SHA256]-----+
2026-03-16 00:08:51.619499 | controller -> localhost | ok: Runtime: 0:00:00.017033
2026-03-16 00:08:51.637976 | 
2026-03-16 00:08:51.638151 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-16 00:08:51.672418 | controller | ok
2026-03-16 00:08:51.685906 | controller | included: /var/lib/zuul/builds/9c60709488604920bb23d71f7829947c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-16 00:08:51.700750 | 
2026-03-16 00:08:51.700871 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-16 00:08:51.726912 | controller | skipping: Conditional result was False
2026-03-16 00:08:51.734232 | 
2026-03-16 00:08:51.734353 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-16 00:08:52.006711 | controller | changed
2026-03-16 00:08:52.023177 | 
2026-03-16 00:08:52.023355 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-16 00:08:52.259938 | controller | ok
2026-03-16 00:08:52.273999 | 
2026-03-16 00:08:52.274223 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-16 00:08:52.905315 | controller | changed
2026-03-16 00:08:52.921456 | 
2026-03-16 00:08:52.921669 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-16 00:08:53.562704 | controller | changed
2026-03-16 00:08:53.576641 | 
2026-03-16 00:08:53.576791 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-16 00:08:53.604297 | controller | skipping: Conditional result was False
2026-03-16 00:08:53.620744 | 
2026-03-16 00:08:53.620928 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-16 00:08:53.875904 | controller -> localhost | changed
2026-03-16 00:08:53.903873 | 
2026-03-16 00:08:53.904107 | TASK [add-build-sshkey : Add back temp key]
2026-03-16 00:08:54.259942 | controller -> localhost | Identity added: /var/lib/zuul/builds/9c60709488604920bb23d71f7829947c/work/9c60709488604920bb23d71f7829947c_id_ecdsa (zuul-build-sshkey)
2026-03-16 00:08:54.260422 | controller -> localhost | ok: Runtime: 0:00:00.015002
2026-03-16 00:08:54.277783 | 
2026-03-16 00:08:54.277978 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-16 00:08:54.657181 | controller | ok
2026-03-16 00:08:54.671248 | 
2026-03-16 00:08:54.671427 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-16 00:08:54.709863 | controller | skipping: Conditional result was False
2026-03-16 00:08:54.742513 | 
2026-03-16 00:08:54.742730 | TASK [include_role : remove-zuul-sshkey]
2026-03-16 00:08:54.770091 | controller | skipping: Conditional result was False
2026-03-16 00:08:54.818500 | 
2026-03-16 00:08:54.818635 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-16 00:08:55.060242 | controller | ok: "logs"
2026-03-16 00:08:55.060747 | controller | ok: All items complete
2026-03-16 00:08:55.060818 | 
2026-03-16 00:08:55.258798 | controller | ok: "artifacts"
2026-03-16 00:08:55.440197 | controller | ok: "docs"
2026-03-16 00:08:55.459821 | 
2026-03-16 00:08:55.459991 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-16 00:08:55.713837 | controller | changed: "logs"
2026-03-16 00:08:55.901709 | controller | changed: "artifacts"
2026-03-16 00:08:56.101513 | controller | changed: "docs"
2026-03-16 00:08:56.169374 | 
2026-03-16 00:08:56.169499 | PLAY RECAP
2026-03-16 00:08:56.169558 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-03-16 00:08:56.169596 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-16 00:08:56.169623 | 
2026-03-16 00:08:56.290987 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-16 00:08:56.291979 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-16 00:08:56.924010 | 
2026-03-16 00:08:56.924180 | PLAY [all]
2026-03-16 00:08:56.948549 | 
2026-03-16 00:08:56.948690 | TASK [Install binary dependencies]
2026-03-16 00:08:56.989232 | controller | ok
2026-03-16 00:08:57.010130 | 
2026-03-16 00:08:57.010248 | TASK [bindep : Include find tasks]
2026-03-16 00:08:57.040093 | controller | ok
2026-03-16 00:08:57.048734 | controller | included: /var/lib/zuul/builds/9c60709488604920bb23d71f7829947c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-03-16 00:08:57.055625 | 
2026-03-16 00:08:57.055702 | TASK [bindep : Look for bindep.txt]
2026-03-16 00:08:57.398323 | controller | ok
2026-03-16 00:08:57.410972 | 
2026-03-16 00:08:57.411166 | TASK [bindep : Define bindep_file fact]
2026-03-16 00:08:57.438716 | controller | skipping: Conditional result was False
2026-03-16 00:08:57.452092 | 
2026-03-16 00:08:57.452238 | TASK [bindep : Look for other-requirements.txt]
2026-03-16 00:08:57.689307 | controller | ok
2026-03-16 00:08:57.703891 | 
2026-03-16 00:08:57.704092 | TASK [bindep : Define bindep_file fact]
2026-03-16 00:08:57.741856 | controller | skipping: Conditional result was False
2026-03-16 00:08:57.756104 | 
2026-03-16 00:08:57.756269 | TASK [bindep : Look for bindep fallback file]
2026-03-16 00:08:57.794324 | controller | skipping: Conditional result was False
2026-03-16 00:08:57.811802 | 
2026-03-16 00:08:57.812261 | TASK [bindep : Define bindep_file fact]
2026-03-16 00:08:57.839717 | controller | skipping: Conditional result was False
2026-03-16 00:08:57.853637 | 
2026-03-16 00:08:57.853789 | TASK [bindep : Include bindep tasks]
2026-03-16 00:08:57.880599 | controller | skipping: Conditional result was False
2026-03-16 00:08:57.895613 | 
2026-03-16 00:08:57.895792 | TASK [bindep : Include install tasks]
2026-03-16 00:08:57.923379 | controller | skipping: Conditional result was False
2026-03-16 00:08:57.937817 | 
2026-03-16 00:08:57.937972 | LOOP [bindep : Include package tasks]
2026-03-16 00:08:58.022108 | 
2026-03-16 00:08:58.022411 | TASK [Run test-setup role]
2026-03-16 00:08:58.059355 | controller | ok
2026-03-16 00:08:58.093492 | 
2026-03-16 00:08:58.093598 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-03-16 00:08:58.336222 | controller | ok
2026-03-16 00:08:58.350860 | 
2026-03-16 00:08:58.351009 | TASK [test-setup : Run tools/test-setup.sh]
2026-03-16 00:08:58.900541 | controller | skipping: Conditional result was False
2026-03-16 00:08:58.954185 | 
2026-03-16 00:08:58.954294 | PLAY RECAP
2026-03-16 00:08:58.954348 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-16 00:08:58.954376 | 
2026-03-16 00:08:59.052908 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-16 00:08:59.054955 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-16 00:08:59.683209 | 
2026-03-16 00:08:59.683368 | PLAY [controller]
2026-03-16 00:08:59.703757 | 
2026-03-16 00:08:59.703853 | TASK [Create the /root directory]
2026-03-16 00:09:00.109618 | controller | ok
2026-03-16 00:09:00.122974 | 
2026-03-16 00:09:00.123176 | TASK [Install glibc-langpack-en]
2026-03-16 00:09:04.123733 | controller | ok: Nothing to do
2026-03-16 00:09:04.138510 | 
2026-03-16 00:09:04.138690 | TASK [Ensure controller directory exists]
2026-03-16 00:09:04.374332 | controller | changed
2026-03-16 00:09:04.389447 | 
2026-03-16 00:09:04.389668 | TASK [Install container runtime]
2026-03-16 00:09:04.466225 | controller | ok
2026-03-16 00:09:04.514554 | 
2026-03-16 00:09:04.514684 | LOOP [ensure-docker : Gather variables for each operating system]
2026-03-16 00:09:04.571571 | controller | ok: "/var/lib/zuul/builds/9c60709488604920bb23d71f7829947c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2026-03-16 00:09:04.591694 | 
2026-03-16 00:09:04.591965 | TASK [ensure-docker : Sanity check for distro selection]
2026-03-16 00:09:04.620881 | controller | skipping: Conditional result was False
2026-03-16 00:09:04.629450 | 
2026-03-16 00:09:04.629548 | TASK [ensure-docker : Sanity check for distro install]
2026-03-16 00:09:04.655473 | controller | skipping: Conditional result was False
2026-03-16 00:09:04.664479 | 
2026-03-16 00:09:04.664610 | TASK [ensure-docker : Sanity check for upstream install]
2026-03-16 00:09:04.689765 | controller | skipping: Conditional result was False
2026-03-16 00:09:04.700041 | 
2026-03-16 00:09:04.700184 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2026-03-16 00:09:04.726263 | controller | skipping: Conditional result was False
2026-03-16 00:09:04.734911 | 
2026-03-16 00:09:04.735010 | TASK [ensure-docker : Include tasks for docker install]
2026-03-16 00:09:04.765243 | controller | ok
2026-03-16 00:09:04.778053 | controller | included: /var/lib/zuul/builds/9c60709488604920bb23d71f7829947c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2026-03-16 00:09:04.790503 | 
2026-03-16 00:09:04.790606 | TASK [ensure-docker : Not Implemented]
2026-03-16 00:09:04.806443 | controller | skipping: Conditional result was False
2026-03-16 00:09:04.814160 | 
2026-03-16 00:09:04.814260 | TASK [Set up docker mirrors]
2026-03-16 00:09:04.834967 | controller | ok
2026-03-16 00:09:04.854623 | 
2026-03-16 00:09:04.854715 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2026-03-16 00:09:04.878954 | controller | skipping: Conditional result was False
2026-03-16 00:09:04.886088 | 
2026-03-16 00:09:04.886181 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2026-03-16 00:09:04.910145 | controller | skipping: Conditional result was False
2026-03-16 00:09:04.916477 | 
2026-03-16 00:09:04.916572 | TASK [use-docker-mirror : Set docker_mirror fact]
2026-03-16 00:09:04.940358 | controller | skipping: Conditional result was False
2026-03-16 00:09:04.946728 | 
2026-03-16 00:09:04.946812 | TASK [use-docker-mirror : Set up docker mirrors]
2026-03-16 00:09:04.970662 | controller | skipping: Conditional result was False
2026-03-16 00:09:04.983056 | 
2026-03-16 00:09:04.983135 | TASK [Add all repositories]
2026-03-16 00:09:05.005624 | controller | ok
2026-03-16 00:09:05.037948 | 
2026-03-16 00:09:05.038073 | LOOP [ensure-package-repositories : Install repositories]
2026-03-16 00:09:05.060330 | controller | ok: "/var/lib/zuul/builds/9c60709488604920bb23d71f7829947c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2026-03-16 00:09:05.060575 | controller | ok: All items complete
2026-03-16 00:09:05.060604 | 
2026-03-16 00:09:05.102550 | controller | included: /var/lib/zuul/builds/9c60709488604920bb23d71f7829947c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2026-03-16 00:09:05.109303 | 
2026-03-16 00:09:05.109377 | TASK [ensure-package-repositories : Create GPG key temp directory]
2026-03-16 00:09:05.408386 | controller | changed
2026-03-16 00:09:05.417513 | 
2026-03-16 00:09:05.417653 | LOOP [ensure-package-repositories : Upload GPG keys]
2026-03-16 00:09:06.309575 | controller | changed:
2026-03-16 00:09:06.309770 | controller | {
2026-03-16 00:09:06.309798 | 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-03-16 00:09:06.309822 | controller | }
2026-03-16 00:09:06.329528 | 
2026-03-16 00:09:06.329761 | LOOP [ensure-package-repositories : Import GPG keys]
2026-03-16 00:09:07.021046 | controller | changed:
2026-03-16 00:09:07.021441 | controller | {
2026-03-16 00:09:07.021511 | 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-03-16 00:09:07.021571 | controller | }
2026-03-16 00:09:07.021687 | controller | changed: All items complete
2026-03-16 00:09:07.021775 | 
2026-03-16 00:09:07.041885 | 
2026-03-16 00:09:07.042140 | TASK [ensure-package-repositories : Remove GPG temp directory]
2026-03-16 00:09:07.269600 | controller | changed
2026-03-16 00:09:07.276682 | 
2026-03-16 00:09:07.276807 | LOOP [ensure-package-repositories : Add repositories]
2026-03-16 00:09:07.602190 | controller | changed:
2026-03-16 00:09:07.602450 | controller | {
2026-03-16 00:09:07.602486 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2026-03-16 00:09:07.602513 | controller |   "description": "Docker CE Stable - $basearch",
2026-03-16 00:09:07.602536 | controller |   "gpgcheck": true,
2026-03-16 00:09:07.602559 | controller |   "name": "docker-ce-stable"
2026-03-16 00:09:07.602581 | controller | }
2026-03-16 00:09:07.637795 | 
2026-03-16 00:09:07.638004 | TASK [ensure-docker : Install pre-reqs]
2026-03-16 00:09:11.338449 | controller | ok: Nothing to do
2026-03-16 00:09:11.352659 | 
2026-03-16 00:09:11.352933 | TASK [ensure-docker : Remove packages]
2026-03-16 00:09:12.713509 | controller | ok: Nothing to do
2026-03-16 00:09:12.732542 | 
2026-03-16 00:09:12.732749 | TASK [ensure-docker : Install upstream docker using package]
2026-03-16 00:10:01.298661 | controller | changed
2026-03-16 00:10:01.313005 | 
2026-03-16 00:10:01.313213 | TASK [ensure-docker : Setup docker]
2026-03-16 00:10:01.342977 | controller | ok
2026-03-16 00:10:01.366486 | controller | included: /var/lib/zuul/builds/9c60709488604920bb23d71f7829947c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2026-03-16 00:10:01.385464 | 
2026-03-16 00:10:01.386274 | TASK [ensure-docker : Ensure "docker" group exists]
2026-03-16 00:10:01.783838 | controller | ok
2026-03-16 00:10:01.799286 | 
2026-03-16 00:10:01.799750 | TASK [ensure-docker : Add user to docker group]
2026-03-16 00:10:02.372388 | controller | changed
2026-03-16 00:10:02.388887 | 
2026-03-16 00:10:02.389152 | TASK [Update docker daemon proxy configuration]
2026-03-16 00:10:02.428646 | controller | skipping: Conditional result was False
2026-03-16 00:10:02.452473 | 
2026-03-16 00:10:02.452604 | TASK [Lower default MTU]
2026-03-16 00:10:02.467098 | controller | skipping: Conditional result was False
2026-03-16 00:10:02.474352 | 
2026-03-16 00:10:02.474414 | TASK [ensure-docker : Get the status of the docket.socket unit]
2026-03-16 00:10:02.839941 | controller | inactive
2026-03-16 00:10:03.009478 | controller | ok: Runtime: 0:00:00.012213
2026-03-16 00:10:03.024362 | 
2026-03-16 00:10:03.024505 | TASK [ensure-docker : Get the status of the docket unit]
2026-03-16 00:10:03.319144 | controller | inactive
2026-03-16 00:10:03.620930 | controller | ok: Runtime: 0:00:00.008562
2026-03-16 00:10:03.637251 | 
2026-03-16 00:10:03.637448 | TASK [ensure-docker : Restart docker]
2026-03-16 00:10:03.708989 | Trigger a Docker restart
2026-03-16 00:10:03.722467 | 
2026-03-16 00:10:03.722667 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2026-03-16 00:10:03.765532 | 
2026-03-16 00:10:03.765690 | TASK [ensure-docker : Install docker-compose]
2026-03-16 00:10:03.791608 | controller | skipping: Conditional result was False
2026-03-16 00:10:03.801353 | 
2026-03-16 00:10:03.801498 | TASK [ensure-docker : Flush handlers before role exit]
2026-03-16 00:10:03.818411 | 
2026-03-16 00:10:03.818550 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2026-03-16 00:10:05.378746 | controller | changed
2026-03-16 00:10:05.399509 | 
2026-03-16 00:10:05.399731 | TASK [ensure-docker : Assure docker service is running]
2026-03-16 00:10:07.443116 | controller | changed
2026-03-16 00:10:07.459737 | 
2026-03-16 00:10:07.459885 | TASK [ensure-docker : Assure docker.socket service is running]
2026-03-16 00:10:08.492755 | controller | ok
2026-03-16 00:10:08.513221 | 
2026-03-16 00:10:08.513417 | TASK [ensure-docker : Correct group ownership on docker sock]
2026-03-16 00:10:09.108847 | controller | ok
2026-03-16 00:10:09.118472 | 
2026-03-16 00:10:09.118618 | TASK [ensure-docker : Validate ability to talk with docker]
2026-03-16 00:10:09.478481 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2026-03-16 00:10:09.667962 | controller | ok: Runtime: 0:00:00.043030
2026-03-16 00:10:09.675098 | 
2026-03-16 00:10:09.675207 | TASK [ensure-docker : Get version details]
2026-03-16 00:10:10.307191 | controller | Client: Docker Engine - Community
2026-03-16 00:10:10.308335 | controller |  Version:           24.0.7
2026-03-16 00:10:10.308348 | controller |  API version:       1.43
2026-03-16 00:10:10.308353 | controller |  Go version:        go1.20.10
2026-03-16 00:10:10.308365 | controller |  Git commit:        afdd53b
2026-03-16 00:10:10.308370 | controller |  Built:             Thu Oct 26 09:09:39 2023
2026-03-16 00:10:10.308375 | controller |  OS/Arch:           linux/amd64
2026-03-16 00:10:10.308381 | controller |  Context:           default
2026-03-16 00:10:10.308387 | controller |
2026-03-16 00:10:10.308392 | controller | Server: Docker Engine - Community
2026-03-16 00:10:10.308396 | controller |  Engine:
2026-03-16 00:10:10.308401 | controller |   Version:          24.0.7
2026-03-16 00:10:10.308405 | controller |   API version:      1.43 (minimum version 1.12)
2026-03-16 00:10:10.308409 | controller |   Go version:       go1.20.10
2026-03-16 00:10:10.308414 | controller |   Git commit:       311b9ff
2026-03-16 00:10:10.308418 | controller |   Built:            Thu Oct 26 09:08:11 2023
2026-03-16 00:10:10.308422 | controller |   OS/Arch:          linux/amd64
2026-03-16 00:10:10.308427 | controller |   Experimental:     false
2026-03-16 00:10:10.308431 | controller |  containerd:
2026-03-16 00:10:10.308435 | controller |   Version:          1.6.25
2026-03-16 00:10:10.308439 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2026-03-16 00:10:10.308444 | controller |  runc:
2026-03-16 00:10:10.308448 | controller |   Version:          1.1.10
2026-03-16 00:10:10.308452 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2026-03-16 00:10:10.308458 | controller |  docker-init:
2026-03-16 00:10:10.308462 | controller |   Version:          0.19.0
2026-03-16 00:10:10.308466 | controller |   GitCommit:        de40ad0
2026-03-16 00:10:10.714789 | controller | ok: Runtime: 0:00:00.081478
2026-03-16 00:10:10.728550 | 
2026-03-16 00:10:10.728727 | TASK [ensure-docker : Dump installed docker details]
2026-03-16 00:10:10.778247 | {'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-03-16 00:10:10.227215', 'end': '2026-03-16 00:10:10.308693', 'delta': '0:00:00.081478', 'msg': '', 'zuul_log_id': 'fa163ec2-ffbe-6272-6fc2-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-03-16 00:10:10.791708 | 
2026-03-16 00:10:10.791848 | TASK [Use buildset registry]
2026-03-16 00:10:10.809396 | controller | skipping: Conditional result was False
2026-03-16 00:10:10.829929 | 
2026-03-16 00:10:10.830105 | TASK [Ensure python3.8 is present]
2026-03-16 00:10:10.855618 | controller | skipping: Conditional result was False
2026-03-16 00:10:10.866096 | 
2026-03-16 00:10:10.866212 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-03-16 00:10:10.890342 | controller | ok
2026-03-16 00:10:10.919516 | 
2026-03-16 00:10:10.919685 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-03-16 00:10:12.841244 | controller | ok: Nothing to do
2026-03-16 00:10:12.859764 | 
2026-03-16 00:10:12.860001 | TASK [our-ensure-python : Also install python3-devel]
2026-03-16 00:10:23.616294 | controller | changed
2026-03-16 00:10:23.633252 | 
2026-03-16 00:10:23.633397 | TASK [Run ensure-virtualenv role]
2026-03-16 00:10:23.656582 | controller | ok
2026-03-16 00:10:23.692094 | 
2026-03-16 00:10:23.692227 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-03-16 00:10:23.902608 | controller | /usr/bin/virtualenv
2026-03-16 00:10:24.224687 | controller | ok: Runtime: 0:00:00.003451
2026-03-16 00:10:24.233532 | 
2026-03-16 00:10:24.233680 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-03-16 00:10:24.255661 | controller | skipping: Conditional result was False
2026-03-16 00:10:24.255934 | controller | changed: All items complete
2026-03-16 00:10:24.255963 | 
2026-03-16 00:10:24.275573 | 
2026-03-16 00:10:24.275726 | TASK [Find the full path of the Python interpreter]
2026-03-16 00:10:24.492155 | controller | /usr/bin/python3.8
2026-03-16 00:10:24.820436 | controller | ok
2026-03-16 00:10:24.835275 | 
2026-03-16 00:10:24.835468 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-03-16 00:10:26.475597 | controller | created virtual environment CPython3.8.18.final.0-64 in 819ms
2026-03-16 00:10:26.506665 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-03-16 00:10:26.506681 | 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-03-16 00:10:26.506690 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-03-16 00:10:26.506709 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-03-16 00:10:26.901173 | controller | changed
2026-03-16 00:10:26.916301 | 
2026-03-16 00:10:26.916527 | TASK [Set selinux package]
2026-03-16 00:10:26.941073 | controller | ok
2026-03-16 00:10:26.948875 | 
2026-03-16 00:10:26.949033 | TASK [Set selinux package (Fedora)]
2026-03-16 00:10:26.992772 | controller | ok
2026-03-16 00:10:27.001112 | 
2026-03-16 00:10:27.001198 | TASK [Install selinux into virtualenv]
2026-03-16 00:10:40.146467 | controller | Collecting selinux-please-lie-to-me
2026-03-16 00:10:52.405479 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-03-16 00:10:52.777986 | controller | Collecting setuptools<50.0.0
2026-03-16 00:10:52.784387 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-03-16 00:10:52.819354 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 25.8 MB/s eta 0:00:00
2026-03-16 00:10:52.897006 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-03-16 00:10:52.897233 | controller |   Attempting uninstall: setuptools
2026-03-16 00:10:52.897948 | controller |     Found existing installation: setuptools 62.6.0
2026-03-16 00:10:52.974662 | controller |     Uninstalling setuptools-62.6.0:
2026-03-16 00:10:52.983372 | controller |       Successfully uninstalled setuptools-62.6.0
2026-03-16 00:10:53.288733 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-03-16 00:11:04.780456 | controller |
2026-03-16 00:11:04.853517 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-03-16 00:11:04.853560 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-03-16 00:11:05.068961 | controller | ok: Runtime: 0:00:37.497138
2026-03-16 00:11:05.079320 | 
2026-03-16 00:11:05.079456 | TASK [Install pytest-forked into virtualenv]
2026-03-16 00:11:17.013339 | controller | Collecting pytest-forked
2026-03-16 00:11:30.294112 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-03-16 00:11:30.334925 | controller | Collecting py
2026-03-16 00:11:30.338380 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-03-16 00:11:30.362664 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 4.5 MB/s eta 0:00:00
2026-03-16 00:11:30.484498 | controller | Collecting pytest>=3.10
2026-03-16 00:11:30.492159 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2026-03-16 00:11:30.504239 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 41.5 MB/s eta 0:00:00
2026-03-16 00:11:30.552203 | controller | Collecting pluggy<2,>=1.5
2026-03-16 00:11:30.555976 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2026-03-16 00:11:30.607004 | controller | Collecting packaging
2026-03-16 00:11:30.611840 | controller |   Downloading packaging-26.0-py3-none-any.whl (74 kB)
2026-03-16 00:11:30.620309 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 11.5 MB/s eta 0:00:00
2026-03-16 00:11:30.653857 | controller | Collecting exceptiongroup>=1.0.0rc8
2026-03-16 00:11:30.657531 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2026-03-16 00:11:30.714004 | controller | Collecting tomli>=1
2026-03-16 00:11:30.717699 | controller |   Downloading tomli-2.4.0-py3-none-any.whl (14 kB)
2026-03-16 00:11:30.749635 | controller | Collecting iniconfig
2026-03-16 00:11:30.754541 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2026-03-16 00:11:30.804693 | controller | Collecting typing-extensions>=4.6.0
2026-03-16 00:11:30.808502 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2026-03-16 00:11:30.816014 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 7.8 MB/s eta 0:00:00
2026-03-16 00:11:30.878398 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2026-03-16 00:11:31.278465 | 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-03-16 00:11:31.281668 | controller |
2026-03-16 00:11:31.343952 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-03-16 00:11:31.343995 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-03-16 00:11:31.636427 | controller | ok: Runtime: 0:00:26.059514
2026-03-16 00:11:31.644423 | 
2026-03-16 00:11:31.644557 | TASK [Update pip]
2026-03-16 00:11:32.205372 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2026-03-16 00:11:42.615775 | controller | Collecting pip
2026-03-16 00:11:54.804226 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2026-03-16 00:11:54.881774 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 25.5 MB/s eta 0:00:00
2026-03-16 00:11:54.936548 | controller | Installing collected packages: pip
2026-03-16 00:11:54.936612 | controller |   Attempting uninstall: pip
2026-03-16 00:11:54.937299 | controller |     Found existing installation: pip 22.2.2
2026-03-16 00:11:55.107138 | controller |     Uninstalling pip-22.2.2:
2026-03-16 00:11:55.122121 | controller |       Successfully uninstalled pip-22.2.2
2026-03-16 00:11:55.892505 | controller | Successfully installed pip-25.0.1
2026-03-16 00:11:56.247824 | controller | ok: Runtime: 0:00:24.076549
2026-03-16 00:11:56.257294 | 
2026-03-16 00:11:56.257440 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-03-16 00:11:56.466509 | controller | changed
2026-03-16 00:11:56.478343 | 
2026-03-16 00:11:56.478484 | TASK [Install ansible into virtualenv]
2026-03-16 00:11:57.021360 | controller | Processing ./src/github.com/ansible/ansible
2026-03-16 00:11:57.022143 | controller |   Preparing metadata (setup.py): started
2026-03-16 00:11:57.449905 | controller |   Preparing metadata (setup.py): finished with status 'done'
2026-03-16 00:12:07.099606 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2026-03-16 00:12:19.355344 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2026-03-16 00:12:19.426202 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2026-03-16 00:12:19.432361 | 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-03-16 00:12:19.690411 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2026-03-16 00:12:19.705076 | controller |   Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2026-03-16 00:12:19.726282 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (26.0)
2026-03-16 00:12:19.858277 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2026-03-16 00:12:19.863762 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2026-03-16 00:12:19.869319 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.8/site-packages (from cryptography->ansible-base==2.10.17.post0) (4.13.2)
2026-03-16 00:12:19.938424 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2026-03-16 00:12:19.944422 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2026-03-16 00:12:19.965683 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2026-03-16 00:12:19.974298 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2026-03-16 00:12:19.997470 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2026-03-16 00:12:20.093691 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 48.6 MB/s eta 0:00:00
2026-03-16 00:12:20.098145 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2026-03-16 00:12:20.112927 | 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-03-16 00:12:20.127306 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 51.0 MB/s eta 0:00:00
2026-03-16 00:12:20.133154 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2026-03-16 00:12:20.151526 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2026-03-16 00:12:20.161814 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2026-03-16 00:12:20.183064 | controller | Building wheels for collected packages: ansible-base
2026-03-16 00:12:20.183822 | controller |   Building wheel for ansible-base (setup.py): started
2026-03-16 00:12:21.394315 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2026-03-16 00:12:21.396631 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=f997ff9d928bcf104f23e970022d5304c89b9dc168d6085fcd1bbcbb132765f6
2026-03-16 00:12:21.396860 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-a2vlhfkp/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2026-03-16 00:12:21.405783 | controller | Successfully built ansible-base
2026-03-16 00:12:21.438860 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2026-03-16 00:12:22.758109 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-base-2.10.17.post0 cffi-1.17.1 cryptography-46.0.5 jinja2-3.1.6 pycparser-2.23
2026-03-16 00:12:23.039549 | controller | ok: Runtime: 0:00:26.112300
2026-03-16 00:12:23.047570 | 
2026-03-16 00:12:23.047705 | TASK [Setup download-artifact-fork role]
2026-03-16 00:12:23.070107 | controller | ok
2026-03-16 00:12:23.094692 | 
2026-03-16 00:12:23.094833 | TASK [download-artifact-fork : Parse inventory response]
2026-03-16 00:12:23.135611 | controller | ok
2026-03-16 00:12:23.142656 | 
2026-03-16 00:12:23.142773 | TASK [download-artifact-fork : Fetch info from Zuul API]
2026-03-16 00:12:23.168633 | controller | skipping: Conditional result was False
2026-03-16 00:12:23.176837 | 
2026-03-16 00:12:23.176935 | TASK [download-artifact-fork : Parse build response]
2026-03-16 00:12:23.203428 | controller | skipping: Conditional result was False
2026-03-16 00:12:23.215149 | 
2026-03-16 00:12:23.215306 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2026-03-16 00:12:23.429621 | controller | changed
2026-03-16 00:12:23.444750 | 
2026-03-16 00:12:23.444978 | LOOP [download-artifact-fork : Download archive by type]
2026-03-16 00:12:23.475848 | controller | skipping: Conditional result was False
2026-03-16 00:12:23.476236 | 
2026-03-16 00:12:24.460590 | controller | changed: OK (20199 bytes)
2026-03-16 00:12:24.482231 | 
2026-03-16 00:12:24.482367 | TASK [deploy-artifacts : define __collections]
2026-03-16 00:12:24.504237 | controller | ok
2026-03-16 00:12:24.510242 | 
2026-03-16 00:12:24.510330 | LOOP [deploy-artifacts : Create list of collections]
2026-03-16 00:12:24.534463 | controller | skipping: Conditional result was False
2026-03-16 00:12:24.541540 | controller | ok:
2026-03-16 00:12:24.541621 | controller | {
2026-03-16 00:12:24.541644 | controller |   "branch": "main",
2026-03-16 00:12:24.541661 | controller |   "job": "build-ansible-collection",
2026-03-16 00:12:24.541677 | controller |   "metadata": {
2026-03-16 00:12:24.541693 | controller |     "type": "ansible_collection",
2026-03-16 00:12:24.541708 | controller |     "version": "5.0.0"
2026-03-16 00:12:24.541724 | controller |   },
2026-03-16 00:12:24.541739 | controller |   "name": "ansible.network",
2026-03-16 00:12:24.541754 | controller |   "project": "ansible-collections/ansible.network",
2026-03-16 00:12:24.541769 | controller |   "url": "https://098327a326dec4909719-922c46b17cda87fc3b2e008fd8af57f8.ssl.cf5.rackcdn.com/ansible/ea04d21fd78c448db82912107d8b9d6d/artifacts/ansible-network-5.0.0.tar.gz"
2026-03-16 00:12:24.541791 | controller | }
2026-03-16 00:12:24.551526 | 
2026-03-16 00:12:24.551631 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2026-03-16 00:12:25.105346 | controller | Starting galaxy collection install process
2026-03-16 00:12:25.105708 | controller | Process install dependency map
2026-03-16 00:20:56.781519 | controller | Starting collection install process
2026-03-16 00:20:56.781738 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2026-03-16 00:20:56.794889 | controller | ansible.network (5.0.0) was installed successfully
2026-03-16 00:20:56.794981 | controller | Installing 'ansible.netcommon:8.4.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-03-16 00:20:56.795165 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-8.4.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4270dk6blob1/tmp8pwmn0ts
2026-03-16 00:21:54.050523 | controller | ansible.netcommon (8.4.0) was installed successfully
2026-03-16 00:21:54.050581 | controller | Installing 'ansible.utils:6.0.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-03-16 00:21:54.050597 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-utils-6.0.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4270dk6blob1/tmp8pwmn0ts
2026-03-16 00:22:58.322530 | controller | ansible.utils (6.0.1) was installed successfully
2026-03-16 00:22:58.322622 | controller | Installing 'arista.eos:12.0.1' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2026-03-16 00:22:58.322755 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/arista-eos-12.0.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4270dk6blob1/tmp8pwmn0ts
2026-03-16 00:23:41.711142 | controller | arista.eos (12.0.1) was installed successfully
2026-03-16 00:23:41.711213 | controller | Installing 'cisco.ios:11.3.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2026-03-16 00:23:41.711236 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.3.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4270dk6blob1/tmp8pwmn0ts
2026-03-16 00:24:22.838050 | controller | cisco.ios (11.3.0) was installed successfully
2026-03-16 00:24:22.838146 | controller | Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2026-03-16 00:24:22.838298 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4270dk6blob1/tmp8pwmn0ts
2026-03-16 00:25:03.783751 | controller | cisco.iosxr (12.1.1) was installed successfully
2026-03-16 00:25:03.783841 | controller | Installing 'cisco.nxos:11.1.3' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2026-03-16 00:25:03.783977 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.3.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4270dk6blob1/tmp8pwmn0ts
2026-03-16 00:25:48.094029 | controller | cisco.nxos (11.1.3) was installed successfully
2026-03-16 00:25:48.094122 | controller | Installing 'junipernetworks.junos:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2026-03-16 00:25:48.094289 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/junipernetworks-junos-11.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4270dk6blob1/tmp8pwmn0ts
2026-03-16 00:26:29.422438 | controller | junipernetworks.junos (11.1.1) was installed successfully
2026-03-16 00:26:29.422531 | controller | Installing 'juniper.device:2.0.1' to '/home/zuul/.ansible/collections/ansible_collections/juniper/device'
2026-03-16 00:26:29.422667 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/juniper-device-2.0.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4270dk6blob1/tmp8pwmn0ts
2026-03-16 00:27:24.122695 | controller | juniper.device (2.0.1) was installed successfully
2026-03-16 00:27:24.499951 | controller | ok: Runtime: 0:14:59.413851
2026-03-16 00:27:24.513062 | 
2026-03-16 00:27:24.513177 | TASK [podman may need iptables kernel modules]
2026-03-16 00:27:25.050595 | controller | ok: Runtime: 0:00:00.003491
2026-03-16 00:27:25.074414 | 
2026-03-16 00:27:25.074521 | PLAY RECAP
2026-03-16 00:27:25.074567 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2026-03-16 00:27:25.074588 | 
2026-03-16 00:27:25.223165 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-16 00:27:25.224164 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-03-16 00:27:25.938046 | 
2026-03-16 00:27:25.938220 | PLAY [controller]
2026-03-16 00:27:25.974926 | 
2026-03-16 00:27:25.975139 | TASK [Copy the galaxy.yml on the controller]
2026-03-16 00:27:26.907456 | controller | changed
2026-03-16 00:27:26.914141 | 
2026-03-16 00:27:26.914223 | TASK [Load information from galaxy.yml]
2026-03-16 00:27:26.947192 | controller | ok
2026-03-16 00:27:26.955854 | 
2026-03-16 00:27:26.955973 | LOOP [Enable FIPS mode]
2026-03-16 00:27:26.981552 | 
2026-03-16 00:27:26.981751 | LOOP [ansible-test : Include branch specific variables.]
2026-03-16 00:27:27.011942 | controller | ok: "/var/lib/zuul/builds/9c60709488604920bb23d71f7829947c/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2026-03-16 00:27:27.012132 | 
2026-03-16 00:27:27.029179 | 
2026-03-16 00:27:27.029367 | TASK [ansible-test : Setup --skip-tags for test_options]
2026-03-16 00:27:27.073278 | controller | ok
2026-03-16 00:27:27.079260 | 
2026-03-16 00:27:27.079353 | TASK [ansible-test : Enable --inventory for network-integration]
2026-03-16 00:27:27.103948 | controller | skipping: Conditional result was False
2026-03-16 00:27:27.110877 | 
2026-03-16 00:27:27.110982 | TASK [ansible-test : Enable --retry-on-error]
2026-03-16 00:27:27.135359 | controller | skipping: Conditional result was False
2026-03-16 00:27:27.142362 | 
2026-03-16 00:27:27.142485 | TASK [ansible-test : Enable --continue-on-error]
2026-03-16 00:27:27.172558 | controller | ok
2026-03-16 00:27:27.178683 | 
2026-03-16 00:27:27.178790 | TASK [ansible-test : Enable --requirements]
2026-03-16 00:27:27.203904 | controller | skipping: Conditional result was False
2026-03-16 00:27:27.212534 | 
2026-03-16 00:27:27.212657 | TASK [ansible-test : Adjust options for unit tests]
2026-03-16 00:27:27.236930 | controller | skipping: Conditional result was False
2026-03-16 00:27:27.244566 | 
2026-03-16 00:27:27.244691 | TASK [ansible-test : Install requirements with unit tests]
2026-03-16 00:27:27.295416 | controller | ok
2026-03-16 00:27:27.301400 | 
2026-03-16 00:27:27.301511 | TASK [ansible-test : Set --skip-test]
2026-03-16 00:27:27.360766 | controller | skipping: Conditional result was False
2026-03-16 00:27:27.373450 | 
2026-03-16 00:27:27.373577 | TASK [ansible-test : Reduce verbosity]
2026-03-16 00:27:27.408372 | controller | ok
2026-03-16 00:27:27.444049 | 
2026-03-16 00:27:27.444183 | TASK [ansible-test : Setup --python option]
2026-03-16 00:27:27.458697 | controller | skipping: Conditional result was False
2026-03-16 00:27:27.465915 | 
2026-03-16 00:27:27.465994 | TASK [ansible-test : Setup --docker option]
2026-03-16 00:27:27.498765 | controller | ok
2026-03-16 00:27:27.504810 | 
2026-03-16 00:27:27.504880 | TASK [ansible-test : check if MANIFEST.json exists]
2026-03-16 00:27:28.052213 | controller | ok
2026-03-16 00:27:28.061742 | 
2026-03-16 00:27:28.062079 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2026-03-16 00:27:28.088314 | controller | skipping: Conditional result was False
2026-03-16 00:27:28.097288 | 
2026-03-16 00:27:28.097432 | TASK [ansible-test : Load information from MANIFEST.json]
2026-03-16 00:27:28.113468 | controller | skipping: Conditional result was False
2026-03-16 00:27:28.120913 | 
2026-03-16 00:27:28.121064 | TASK [ansible-test : Setup location of project for integration tests]
2026-03-16 00:27:28.135377 | controller | skipping: Conditional result was False
2026-03-16 00:27:28.142853 | 
2026-03-16 00:27:28.142981 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2026-03-16 00:27:28.787150 | controller | ok
2026-03-16 00:27:28.795290 | 
2026-03-16 00:27:28.795406 | TASK [ansible-test : Load information from galaxy.yml]
2026-03-16 00:27:28.833949 | controller | ok
2026-03-16 00:27:28.841966 | 
2026-03-16 00:27:28.842355 | TASK [ansible-test : Setup location of project for integration tests]
2026-03-16 00:27:28.872854 | controller | ok
2026-03-16 00:27:28.878592 | 
2026-03-16 00:27:28.878680 | TASK [ansible-test : Setup minimum test requirements]
2026-03-16 00:27:28.909444 | controller | ok
2026-03-16 00:27:28.915086 | 
2026-03-16 00:27:28.915170 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2026-03-16 00:27:29.407186 | controller | ok
2026-03-16 00:27:29.421640 | 
2026-03-16 00:27:29.421759 | TASK [ansible-test : Install python requirements]
2026-03-16 00:27:43.432699 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-03-16 00:28:07.620199 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2026-03-16 00:28:07.663232 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-03-16 00:28:07.669496 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2026-03-16 00:28:07.679804 | 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-03-16 00:28:07.686940 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2026-03-16 00:28:07.705482 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2026-03-16 00:28:07.752214 | controller | Installing collected packages: pathspec, yamllint
2026-03-16 00:28:07.816922 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2026-03-16 00:28:07.972904 | controller | ok: Runtime: 0:00:37.779723
2026-03-16 00:28:07.982635 | 
2026-03-16 00:28:07.982738 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2026-03-16 00:28:08.215471 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2026-03-16 00:28:08.528871 | controller | ERROR
2026-03-16 00:28:08.529148 | controller | {
2026-03-16 00:28:08.529195 | controller |   "delta": "0:00:00.005028",
2026-03-16 00:28:08.529224 | controller |   "end": "2026-03-16 00:28:08.215611",
2026-03-16 00:28:08.529249 | controller |   "msg": "non-zero return code",
2026-03-16 00:28:08.529272 | controller |   "rc": 1,
2026-03-16 00:28:08.529296 | controller |   "start": "2026-03-16 00:28:08.210583"
2026-03-16 00:28:08.529318 | controller | }
2026-03-16 00:28:08.529350 | controller | ERROR: Ignoring Errors
2026-03-16 00:28:08.535973 | 
2026-03-16 00:28:08.536125 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2026-03-16 00:28:09.080089 | controller | skipping: Conditional result was False
2026-03-16 00:28:09.098160 | 
2026-03-16 00:28:09.098447 | TASK [ansible-test : Enable persistent connection logging]
2026-03-16 00:28:09.890763 | controller | changed: section and option added
2026-03-16 00:28:09.900206 | 
2026-03-16 00:28:09.900306 | TASK [ansible-test : Set persistent ssh_type value]
2026-03-16 00:28:10.370818 | controller | changed: option added
2026-03-16 00:28:10.380125 | 
2026-03-16 00:28:10.380261 | TASK [ansible-test : Set ansible log path]
2026-03-16 00:28:10.944110 | controller | changed: section and option added
2026-03-16 00:28:10.963164 | 
2026-03-16 00:28:10.963369 | TASK [ansible-test : Identify the changed targets]
2026-03-16 00:28:11.519626 | controller | skipping: Conditional result was False
2026-03-16 00:28:11.526270 | 
2026-03-16 00:28:11.526367 | TASK [ansible-test : set_fact]
2026-03-16 00:28:11.642220 | controller | skipping: Conditional result was False
2026-03-16 00:28:11.688909 | 
2026-03-16 00:28:11.689066 | TASK [ansible-test : Set the targets]
2026-03-16 00:28:11.723770 | controller | ok
2026-03-16 00:28:11.731567 | 
2026-03-16 00:28:11.731655 | TASK [ansible-test : set_fact]
2026-03-16 00:28:11.746704 | controller | skipping: Conditional result was False
2026-03-16 00:28:11.756124 | 
2026-03-16 00:28:11.756226 | TASK [ansible-test : List the targets]
2026-03-16 00:28:11.806404 | controller | skipping: Conditional result was False
2026-03-16 00:28:11.813290 | 
2026-03-16 00:28:11.813378 | TASK [ansible-test : set_fact]
2026-03-16 00:28:11.838216 | controller | skipping: Conditional result was False
2026-03-16 00:28:11.846282 | 
2026-03-16 00:28:11.846414 | TASK [ansible-test : set_fact]
2026-03-16 00:28:11.860883 | controller | skipping: Conditional result was False
2026-03-16 00:28:11.868588 | 
2026-03-16 00:28:11.868703 | LOOP [ansible-test : set_fact]
2026-03-16 00:28:11.920076 | 
2026-03-16 00:28:11.920247 | TASK [ansible-test : set_fact]
2026-03-16 00:28:11.944869 | controller | skipping: Conditional result was False
2026-03-16 00:28:11.953578 | 
2026-03-16 00:28:11.953701 | TASK [ansible-test : debug]
2026-03-16 00:28:11.978157 | controller | skipping: Conditional result was False
2026-03-16 00:28:11.985257 | 
2026-03-16 00:28:11.985374 | TASK [ansible-test : debug]
2026-03-16 00:28:12.017600 | About to run: ansible-test sanity --requirements --docker -v
2026-03-16 00:28:12.026079 | 
2026-03-16 00:28:12.026231 | TASK [ansible-test : Run the test suite]
2026-03-16 00:28:12.834669 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2026-03-16 00:28:12.881452 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2026-03-16 00:28:14.711226 | controller | 2.9.0: Pulling from ansible/default-test-container
2026-03-16 00:28:14.711868 | controller | 171857c49d0f: Pulling fs layer
2026-03-16 00:28:14.711892 | controller | 419640447d26: Pulling fs layer
2026-03-16 00:28:14.711898 | controller | 61e52f862619: Pulling fs layer
2026-03-16 00:28:14.711903 | controller | 851de0426e26: Pulling fs layer
2026-03-16 00:28:14.711936 | controller | 37425716ea5b: Pulling fs layer
2026-03-16 00:28:14.711944 | controller | cd9b0c439616: Pulling fs layer
2026-03-16 00:28:14.711949 | controller | d4612dbd8855: Pulling fs layer
2026-03-16 00:28:14.711953 | controller | 554699364730: Pulling fs layer
2026-03-16 00:28:14.711957 | controller | 1ff15d4bdb3e: Pulling fs layer
2026-03-16 00:28:14.711961 | controller | 5f2e4724adea: Pulling fs layer
2026-03-16 00:28:14.711965 | controller | a02d9a95ccb8: Pulling fs layer
2026-03-16 00:28:14.711969 | controller | 989f4ce370cb: Pulling fs layer
2026-03-16 00:28:14.711973 | controller | 0d7f52cdf9df: Pulling fs layer
2026-03-16 00:28:14.711977 | controller | c423742351d5: Pulling fs layer
2026-03-16 00:28:14.711982 | controller | 109236341d21: Pulling fs layer
2026-03-16 00:28:14.711986 | controller | 07a2f61c96b8: Pulling fs layer
2026-03-16 00:28:14.711992 | controller | 041f122f27ae: Pulling fs layer
2026-03-16 00:28:14.711996 | controller | ca6f5098b12e: Pulling fs layer
2026-03-16 00:28:14.712001 | controller | 1eb3e7b2afd9: Pulling fs layer
2026-03-16 00:28:14.712005 | controller | 18a0b750727a: Pulling fs layer
2026-03-16 00:28:14.712009 | controller | 17f2f596716c: Pulling fs layer
2026-03-16 00:28:14.712015 | controller | f35a9998a9bd: Pulling fs layer
2026-03-16 00:28:14.712019 | controller | fdfc10566405: Pulling fs layer
2026-03-16 00:28:14.712023 | controller | 1ff1c442c5fe: Pulling fs layer
2026-03-16 00:28:14.712027 | controller | a3443c4a17ed: Pulling fs layer
2026-03-16 00:28:14.712031 | controller | 0edcf9fb2b15: Pulling fs layer
2026-03-16 00:28:14.712035 | controller | 5af43b69e845: Pulling fs layer
2026-03-16 00:28:14.712039 | controller | 1651773effb2: Pulling fs layer
2026-03-16 00:28:14.712043 | controller | 79e23c5c0fe4: Pulling fs layer
2026-03-16 00:28:14.712050 | controller | e5058b1faaa6: Pulling fs layer
2026-03-16 00:28:14.712054 | controller | 846775490569: Pulling fs layer
2026-03-16 00:28:14.712062 | controller | 041f122f27ae: Waiting
2026-03-16 00:28:15.740868 | controller | ca6f5098b12e: Waiting
2026-03-16 00:28:15.740899 | controller | 1eb3e7b2afd9: Waiting
2026-03-16 00:28:15.740904 | controller | 18a0b750727a: Waiting
2026-03-16 00:28:15.740923 | controller | 17f2f596716c: Waiting
2026-03-16 00:28:15.740928 | controller | f35a9998a9bd: Waiting
2026-03-16 00:28:15.740937 | controller | fdfc10566405: Waiting
2026-03-16 00:28:15.740941 | controller | 1ff1c442c5fe: Waiting
2026-03-16 00:28:15.740945 | controller | a3443c4a17ed: Waiting
2026-03-16 00:28:15.740949 | controller | 0edcf9fb2b15: Waiting
2026-03-16 00:28:15.740953 | controller | 5af43b69e845: Waiting
2026-03-16 00:28:15.740957 | controller | 1651773effb2: Waiting
2026-03-16 00:28:15.740962 | controller | 79e23c5c0fe4: Waiting
2026-03-16 00:28:15.740966 | controller | e5058b1faaa6: Waiting
2026-03-16 00:28:15.740970 | controller | 846775490569: Waiting
2026-03-16 00:28:15.740974 | controller | 851de0426e26: Waiting
2026-03-16 00:28:15.740978 | controller | 37425716ea5b: Waiting
2026-03-16 00:28:15.740995 | controller | cd9b0c439616: Waiting
2026-03-16 00:28:15.741004 | controller | a02d9a95ccb8: Waiting
2026-03-16 00:28:15.741008 | controller | d4612dbd8855: Waiting
2026-03-16 00:28:15.741012 | controller | 989f4ce370cb: Waiting
2026-03-16 00:28:15.741016 | controller | 554699364730: Waiting
2026-03-16 00:28:15.741020 | controller | 0d7f52cdf9df: Waiting
2026-03-16 00:28:15.741024 | controller | 1ff15d4bdb3e: Waiting
2026-03-16 00:28:15.741028 | controller | c423742351d5: Waiting
2026-03-16 00:28:15.741032 | controller | 109236341d21: Waiting
2026-03-16 00:28:15.741036 | controller | 07a2f61c96b8: Waiting
2026-03-16 00:28:15.741041 | controller | 5f2e4724adea: Waiting
2026-03-16 00:28:15.741053 | controller | 61e52f862619: Verifying Checksum
2026-03-16 00:28:15.779107 | controller | 61e52f862619: Download complete
2026-03-16 00:28:15.779129 | controller | 419640447d26: Verifying Checksum
2026-03-16 00:28:16.500360 | controller | 419640447d26: Download complete
2026-03-16 00:28:16.500410 | controller | 171857c49d0f: Verifying Checksum
2026-03-16 00:28:16.768823 | controller | 171857c49d0f: Download complete
2026-03-16 00:28:16.768882 | controller | 851de0426e26: Verifying Checksum
2026-03-16 00:28:17.517530 | controller | 851de0426e26: Download complete
2026-03-16 00:28:17.517574 | controller | cd9b0c439616: Verifying Checksum
2026-03-16 00:28:17.732009 | controller | cd9b0c439616: Download complete
2026-03-16 00:28:17.732054 | controller | d4612dbd8855: Verifying Checksum
2026-03-16 00:28:17.732360 | controller | d4612dbd8855: Download complete
2026-03-16 00:28:18.625948 | controller | 171857c49d0f: Pull complete
2026-03-16 00:28:18.655391 | controller | 419640447d26: Pull complete
2026-03-16 00:28:18.677455 | controller | 61e52f862619: Pull complete
2026-03-16 00:28:18.694623 | controller | 1ff15d4bdb3e: Verifying Checksum
2026-03-16 00:28:18.697000 | controller | 1ff15d4bdb3e: Download complete
2026-03-16 00:28:18.697017 | controller | 851de0426e26: Pull complete
2026-03-16 00:28:19.678434 | controller | 5f2e4724adea: Verifying Checksum
2026-03-16 00:28:20.805014 | controller | 5f2e4724adea: Download complete
2026-03-16 00:28:20.805077 | controller | a02d9a95ccb8: Verifying Checksum
2026-03-16 00:28:21.741075 | controller | a02d9a95ccb8: Download complete
2026-03-16 00:28:21.741146 | controller | 989f4ce370cb: Verifying Checksum
2026-03-16 00:28:21.741379 | controller | 989f4ce370cb: Download complete
2026-03-16 00:28:22.015364 | controller | 554699364730: Verifying Checksum
2026-03-16 00:28:22.613334 | controller | 554699364730: Download complete
2026-03-16 00:28:22.613411 | controller | 0d7f52cdf9df: Verifying Checksum
2026-03-16 00:28:22.997166 | controller | 0d7f52cdf9df: Download complete
2026-03-16 00:28:22.997223 | controller | c423742351d5: Verifying Checksum
2026-03-16 00:28:24.163645 | controller | c423742351d5: Download complete
2026-03-16 00:28:24.163695 | controller | 07a2f61c96b8: Verifying Checksum
2026-03-16 00:28:25.126910 | controller | 07a2f61c96b8: Download complete
2026-03-16 00:28:25.126985 | controller | 041f122f27ae: Verifying Checksum
2026-03-16 00:28:25.808975 | controller | 041f122f27ae: Download complete
2026-03-16 00:28:25.809046 | controller | 109236341d21: Verifying Checksum
2026-03-16 00:28:26.231955 | controller | 109236341d21: Download complete
2026-03-16 00:28:26.232030 | controller | ca6f5098b12e: Verifying Checksum
2026-03-16 00:28:26.232069 | controller | ca6f5098b12e: Download complete
2026-03-16 00:28:26.796471 | controller | 1eb3e7b2afd9: Verifying Checksum
2026-03-16 00:28:26.796556 | controller | 1eb3e7b2afd9: Download complete
2026-03-16 00:28:27.357971 | controller | 18a0b750727a: Verifying Checksum
2026-03-16 00:28:27.358018 | controller | 18a0b750727a: Download complete
2026-03-16 00:28:27.725748 | controller | 17f2f596716c: Download complete
2026-03-16 00:28:28.191938 | controller | f35a9998a9bd: Verifying Checksum
2026-03-16 00:28:28.710866 | controller | f35a9998a9bd: Download complete
2026-03-16 00:28:28.710932 | controller | fdfc10566405: Verifying Checksum
2026-03-16 00:28:29.076754 | controller | fdfc10566405: Download complete
2026-03-16 00:28:29.076823 | controller | 1ff1c442c5fe: Verifying Checksum
2026-03-16 00:28:29.076971 | controller | 1ff1c442c5fe: Download complete
2026-03-16 00:28:30.314984 | controller | a3443c4a17ed: Verifying Checksum
2026-03-16 00:28:30.970707 | controller | a3443c4a17ed: Download complete
2026-03-16 00:28:30.970774 | controller | 0edcf9fb2b15: Verifying Checksum
2026-03-16 00:28:30.970854 | controller | 0edcf9fb2b15: Download complete
2026-03-16 00:28:33.032471 | controller | 1651773effb2: Verifying Checksum
2026-03-16 00:28:33.032557 | controller | 1651773effb2: Download complete
2026-03-16 00:28:34.012349 | controller | 5af43b69e845: Verifying Checksum
2026-03-16 00:28:34.012432 | controller | 5af43b69e845: Download complete
2026-03-16 00:28:34.569743 | controller | 79e23c5c0fe4: Verifying Checksum
2026-03-16 00:28:34.569845 | controller | 79e23c5c0fe4: Download complete
2026-03-16 00:28:34.748207 | controller | 37425716ea5b: Verifying Checksum
2026-03-16 00:28:34.748324 | controller | 37425716ea5b: Download complete
2026-03-16 00:28:36.038437 | controller | e5058b1faaa6: Verifying Checksum
2026-03-16 00:28:36.038523 | controller | e5058b1faaa6: Download complete
2026-03-16 00:28:36.830030 | controller | 846775490569: Verifying Checksum
2026-03-16 00:28:46.283433 | controller | 846775490569: Download complete
2026-03-16 00:28:46.283953 | controller | 37425716ea5b: Pull complete
2026-03-16 00:28:46.310355 | controller | cd9b0c439616: Pull complete
2026-03-16 00:28:46.329363 | controller | d4612dbd8855: Pull complete
2026-03-16 00:28:48.905826 | controller | 554699364730: Pull complete
2026-03-16 00:28:48.933859 | controller | 1ff15d4bdb3e: Pull complete
2026-03-16 00:28:48.951943 | controller | 5f2e4724adea: Pull complete
2026-03-16 00:28:48.990189 | controller | a02d9a95ccb8: Pull complete
2026-03-16 00:28:49.008252 | controller | 989f4ce370cb: Pull complete
2026-03-16 00:28:49.026610 | controller | 0d7f52cdf9df: Pull complete
2026-03-16 00:28:49.045009 | controller | c423742351d5: Pull complete
2026-03-16 00:28:50.277299 | controller | 109236341d21: Pull complete
2026-03-16 00:28:50.302009 | controller | 07a2f61c96b8: Pull complete
2026-03-16 00:28:50.324209 | controller | 041f122f27ae: Pull complete
2026-03-16 00:28:50.418735 | controller | ca6f5098b12e: Pull complete
2026-03-16 00:28:50.465376 | controller | 1eb3e7b2afd9: Pull complete
2026-03-16 00:28:50.506794 | controller | 18a0b750727a: Pull complete
2026-03-16 00:28:50.529746 | controller | 17f2f596716c: Pull complete
2026-03-16 00:28:50.549785 | controller | f35a9998a9bd: Pull complete
2026-03-16 00:28:50.596946 | controller | fdfc10566405: Pull complete
2026-03-16 00:28:50.633197 | controller | 1ff1c442c5fe: Pull complete
2026-03-16 00:28:51.576711 | controller | a3443c4a17ed: Pull complete
2026-03-16 00:28:52.783824 | controller | 0edcf9fb2b15: Pull complete
2026-03-16 00:28:54.278328 | controller | 5af43b69e845: Pull complete
2026-03-16 00:28:55.973547 | controller | 1651773effb2: Pull complete
2026-03-16 00:28:57.743185 | controller | 79e23c5c0fe4: Pull complete
2026-03-16 00:28:59.772648 | controller | e5058b1faaa6: Pull complete
2026-03-16 00:29:01.576080 | controller | 846775490569: Pull complete
2026-03-16 00:29:01.592357 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2026-03-16 00:29:01.596749 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2026-03-16 00:29:01.603601 | controller | quay.io/ansible/default-test-container:2.9.0
2026-03-16 00:29:01.605657 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2026-03-16 00:29:01.627159 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2026-03-16 00:29:03.334858 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2026-03-16 00:29:03.338478 | controller | 04a5f4cda3ee: Pulling fs layer
2026-03-16 00:29:03.340931 | controller | ff496a88c8ed: Pulling fs layer
2026-03-16 00:29:03.340973 | controller | 0ce83f459fe7: Pulling fs layer
2026-03-16 00:29:03.340990 | controller | 2e5170e1f099: Pulling fs layer
2026-03-16 00:29:03.341004 | controller | 7641eb41b08c: Pulling fs layer
2026-03-16 00:29:03.341021 | controller | ad15fa9da398: Pulling fs layer
2026-03-16 00:29:03.341035 | controller | 087d91352424: Pulling fs layer
2026-03-16 00:29:03.341049 | controller | 8b92efd6a100: Pulling fs layer
2026-03-16 00:29:03.341066 | controller | 7641eb41b08c: Waiting
2026-03-16 00:29:03.341247 | controller | ad15fa9da398: Waiting
2026-03-16 00:29:03.341289 | controller | 087d91352424: Waiting
2026-03-16 00:29:03.341303 | controller | 8b92efd6a100: Waiting
2026-03-16 00:29:04.242613 | controller | 2e5170e1f099: Waiting
2026-03-16 00:29:04.242675 | controller | ff496a88c8ed: Verifying Checksum
2026-03-16 00:29:04.242714 | controller | ff496a88c8ed: Download complete
2026-03-16 00:29:04.250245 | controller | 0ce83f459fe7: Verifying Checksum
2026-03-16 00:29:05.149346 | controller | 0ce83f459fe7: Download complete
2026-03-16 00:29:05.149416 | controller | 7641eb41b08c: Verifying Checksum
2026-03-16 00:29:05.581326 | controller | 7641eb41b08c: Download complete
2026-03-16 00:29:05.581426 | controller | 04a5f4cda3ee: Verifying Checksum
2026-03-16 00:29:05.581906 | controller | 04a5f4cda3ee: Download complete
2026-03-16 00:29:05.921597 | controller | 2e5170e1f099: Verifying Checksum
2026-03-16 00:29:05.921709 | controller | 2e5170e1f099: Download complete
2026-03-16 00:29:06.110373 | controller | ad15fa9da398: Verifying Checksum
2026-03-16 00:29:06.110481 | controller | ad15fa9da398: Download complete
2026-03-16 00:29:06.615092 | controller | 087d91352424: Verifying Checksum
2026-03-16 00:29:06.615162 | controller | 087d91352424: Download complete
2026-03-16 00:29:06.817590 | controller | 04a5f4cda3ee: Pull complete
2026-03-16 00:29:06.856097 | controller | ff496a88c8ed: Pull complete
2026-03-16 00:29:06.865528 | controller | 8b92efd6a100: Verifying Checksum
2026-03-16 00:29:06.865574 | controller | 8b92efd6a100: Download complete
2026-03-16 00:29:06.912949 | controller | 0ce83f459fe7: Pull complete
2026-03-16 00:29:07.796411 | controller | 2e5170e1f099: Pull complete
2026-03-16 00:29:07.820911 | controller | 7641eb41b08c: Pull complete
2026-03-16 00:29:07.861886 | controller | ad15fa9da398: Pull complete
2026-03-16 00:29:08.462572 | controller | 087d91352424: Pull complete
2026-03-16 00:29:08.516911 | controller | 8b92efd6a100: Pull complete
2026-03-16 00:29:08.545065 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2026-03-16 00:29:08.650685 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2026-03-16 00:29:08.728403 | controller | quay.io/ansible/pypi-test-container:1.0.0
2026-03-16 00:29:08.733430 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2026-03-16 00:29:16.329863 | controller | Run command: docker inspect f376dedd954fda90e32e3fb19f633e00df149bb65815d5d678032926fbd58d97
2026-03-16 00:29:16.365419 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2026-03-16 00:29:16.374062 | controller | Including collection: ansible.netcommon (278 files)
2026-03-16 00:29:16.381710 | controller | Including collection: ansible.network (23 files)
2026-03-16 00:29:16.381728 | controller | Including collection: ansible.utils (441 files)
2026-03-16 00:29:16.407869 | controller | Including collection: arista.eos (1031 files)
2026-03-16 00:29:16.437186 | controller | Including collection: cisco.ios (1215 files)
2026-03-16 00:29:16.465046 | controller | Including collection: cisco.iosxr (1044 files)
2026-03-16 00:29:16.511615 | controller | Including collection: cisco.nxos (1687 files)
2026-03-16 00:29:16.522689 | controller | Including collection: juniper.device (391 files)
2026-03-16 00:29:16.542709 | controller | Including collection: junipernetworks.junos (700 files)
2026-03-16 00:29:16.566218 | controller | Creating a payload archive containing 7536 files...
2026-03-16 00:29:18.112121 | controller | Created a 6222727 byte payload archive containing 7536 files in 1 seconds.
2026-03-16 00:29:18.113280 | controller | Assuming Docker is available on localhost.
2026-03-16 00:29:18.113533 | controller | Run command: docker run --detach --volume /sys/fs/cgroup:/sys/fs/cgroup:ro --privileged=false --security-opt seccomp=unconfined --volume /var/run/docker.sock:/var/run/docker.sock quay.io/ansible/default-test-container:2.9.0
2026-03-16 00:29:18.514237 | controller | Run command: docker exec -i df7dbd0f99af80e431a8cae0353acbc096ee7ef942ba8483b0364b4e87de6e4e dd of=/root/docker.sh bs=65536
2026-03-16 00:29:18.580679 | controller | Run command: docker exec df7dbd0f99af80e431a8cae0353acbc096ee7ef942ba8483b0364b4e87de6e4e /bin/bash /root/docker.sh
2026-03-16 00:29:18.630523 | controller | Run command: docker exec -i df7dbd0f99af80e431a8cae0353acbc096ee7ef942ba8483b0364b4e87de6e4e dd of=/root/test.tgz bs=65536
2026-03-16 00:29:18.697364 | controller | Run command: docker exec df7dbd0f99af80e431a8cae0353acbc096ee7ef942ba8483b0364b4e87de6e4e tar oxzf /root/test.tgz -C /root
2026-03-16 00:29:19.354430 | controller | Run command: docker exec df7dbd0f99af80e431a8cae0353acbc096ee7ef942ba8483b0364b4e87de6e4e /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata tests/output/.tmp/metadata-x_q82kln.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2026-03-16 00:29:19.606623 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2026-03-16 00:29:19.606967 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2026-03-16 00:29:19.608106 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2026-03-16 00:29:19.608139 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2026-03-16 00:29:19.608634 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2026-03-16 00:29:19.613285 | controller | No tests applicable.
2026-03-16 00:29:19.613484 | controller | Running sanity test 'ansible-doc' with Python 3.6
2026-03-16 00:29:19.613511 | controller | No tests applicable.
2026-03-16 00:29:19.613833 | controller | Running sanity test 'changelog' with Python 3.6
2026-03-16 00:29:19.613851 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/ansible-test.txt
2026-03-16 00:29:20.199280 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt
2026-03-16 00:29:20.574871 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2026-03-16 00:29:20.604916 | controller |   ERROR: Could not find a version that satisfies the requirement antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2)) (from versions: none)
2026-03-16 00:29:20.606060 | controller | ERROR: No matching distribution found for antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2026-03-16 00:29:20.649372 | controller | ERROR: Command "/usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt" returned exit status 1.
2026-03-16 00:29:20.650000 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2026-03-16 00:29:20.650331 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2026-03-16 00:29:20.650585 | controller | Removing custom PyPI hosts entries: /etc/hosts
2026-03-16 00:29:20.673121 | controller | Run command: docker exec df7dbd0f99af80e431a8cae0353acbc096ee7ef942ba8483b0364b4e87de6e4e tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2026-03-16 00:29:20.729906 | controller | Run command: docker exec -i df7dbd0f99af80e431a8cae0353acbc096ee7ef942ba8483b0364b4e87de6e4e dd if=/root/output.tgz bs=65536
2026-03-16 00:29:20.782592 | controller | Run command: tar oxzf /tmp/ansible-result-0pbco6nb.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2026-03-16 00:29:20.793239 | controller | Run command: docker rm -f f376dedd954fda90e32e3fb19f633e00df149bb65815d5d678032926fbd58d97
2026-03-16 00:29:21.440371 | controller | Run command: docker rm -f df7dbd0f99af80e431a8cae0353acbc096ee7ef942ba8483b0364b4e87de6e4e
2026-03-16 00:29:21.852493 | controller | ERROR: Command "docker exec df7dbd0f99af80e431a8cae0353acbc096ee7ef942ba8483b0364b4e87de6e4e /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata tests/output/.tmp/metadata-x_q82kln.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2026-03-16 00:29:22.131728 | controller | ERROR
2026-03-16 00:29:22.132005 | controller | {
2026-03-16 00:29:22.132137 | controller |   "delta": "0:01:09.199139",
2026-03-16 00:29:22.132190 | controller |   "end": "2026-03-16 00:29:21.872877",
2026-03-16 00:29:22.132245 | controller |   "msg": "non-zero return code",
2026-03-16 00:29:22.132300 | controller |   "rc": 1,
2026-03-16 00:29:22.132358 | controller |   "start": "2026-03-16 00:28:12.673738"
2026-03-16 00:29:22.132415 | controller | }
failure
2026-03-16 00:29:22.139600 | 
2026-03-16 00:29:22.139750 | PLAY RECAP
2026-03-16 00:29:22.139865 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2026-03-16 00:29:22.139933 | 
2026-03-16 00:29:22.278479 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-03-16 00:29:22.280045 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-16 00:29:22.860789 | 
2026-03-16 00:29:22.860920 | PLAY [all]
2026-03-16 00:29:22.885113 | 
2026-03-16 00:29:22.885260 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-03-16 00:29:23.347727 | controller | changed: non-zero return code
2026-03-16 00:29:23.363853 | 
2026-03-16 00:29:23.364088 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-03-16 00:29:23.392254 | controller | skipping: Conditional result was False
2026-03-16 00:29:23.402118 | 
2026-03-16 00:29:23.402295 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-03-16 00:29:23.444281 | 
2026-03-16 00:29:23.444568 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-03-16 00:29:23.480509 | 
2026-03-16 00:29:23.480701 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-03-16 00:29:23.506052 | controller | skipping: Conditional result was False
2026-03-16 00:29:23.517164 | 
2026-03-16 00:29:23.517287 | LOOP [fetch-subunit-output : Generate subunit file]
2026-03-16 00:29:23.552713 | 
2026-03-16 00:29:23.552917 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-03-16 00:29:23.577811 | controller | skipping: Conditional result was False
2026-03-16 00:29:23.583994 | 
2026-03-16 00:29:23.584089 | TASK [fetch-subunit-output : Remove the temporary file]
2026-03-16 00:29:23.598259 | controller | skipping: Conditional result was False
2026-03-16 00:29:23.605505 | 
2026-03-16 00:29:23.605596 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-03-16 00:29:23.630137 | controller | skipping: Conditional result was False
2026-03-16 00:29:23.657951 | 
2026-03-16 00:29:23.658067 | PLAY RECAP
2026-03-16 00:29:23.658148 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-16 00:29:23.658171 | 
2026-03-16 00:29:23.758162 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-16 00:29:23.759138 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-03-16 00:29:24.395079 | 
2026-03-16 00:29:24.395227 | PLAY [all:!appliance*]
2026-03-16 00:29:24.419386 | 
2026-03-16 00:29:24.419510 | TASK [unregister the node]
2026-03-16 00:29:24.953993 | controller | skipping: Conditional result was False
2026-03-16 00:29:24.968515 | 
2026-03-16 00:29:24.968722 | TASK [include_role : fetch-output]
2026-03-16 00:29:25.021360 | controller | ok
2026-03-16 00:29:25.058187 | 
2026-03-16 00:29:25.058345 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-16 00:29:25.103030 | controller | skipping: Conditional result was False
2026-03-16 00:29:25.110341 | 
2026-03-16 00:29:25.110434 | TASK [fetch-output : Set log path for single node]
2026-03-16 00:29:25.150678 | controller | ok
2026-03-16 00:29:25.157268 | 
2026-03-16 00:29:25.157419 | LOOP [fetch-output : Ensure local output dirs]
2026-03-16 00:29:25.579675 | controller -> localhost | ok: "/var/lib/zuul/builds/9c60709488604920bb23d71f7829947c/work/logs"
2026-03-16 00:29:25.804165 | controller -> localhost | changed: "/var/lib/zuul/builds/9c60709488604920bb23d71f7829947c/work/artifacts"
2026-03-16 00:29:26.037909 | controller -> localhost | changed: "/var/lib/zuul/builds/9c60709488604920bb23d71f7829947c/work/docs"
2026-03-16 00:29:26.053620 | 
2026-03-16 00:29:26.053731 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-16 00:29:26.813913 | controller | changed:
2026-03-16 00:29:26.814335 | controller | .d..t...... ./
2026-03-16 00:29:26.814404 | controller | cd+++++++++ controller/
2026-03-16 00:29:26.814484 | controller | changed: All items complete
2026-03-16 00:29:26.814534 | 
2026-03-16 00:29:27.388498 | controller | changed: .d..t...... ./
2026-03-16 00:29:27.997359 | controller | changed: .d..t...... ./
2026-03-16 00:29:28.014701 | 
2026-03-16 00:29:28.014819 | TASK [include_role : fetch-output-openshift]
2026-03-16 00:29:28.038816 | controller | skipping: Conditional result was False
2026-03-16 00:29:28.049130 | 
2026-03-16 00:29:28.049254 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-16 00:29:28.088333 | controller | skipping: Conditional result was False
2026-03-16 00:29:28.102207 | controller | skipping: Conditional result was False
2026-03-16 00:29:28.148280 | 
2026-03-16 00:29:28.148414 | PLAY [localhost]
2026-03-16 00:29:28.166683 | 
2026-03-16 00:29:28.166816 | TASK [Run Zuul manifest role]
2026-03-16 00:29:28.189435 | localhost | ok
2026-03-16 00:29:28.211213 | 
2026-03-16 00:29:28.211378 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-16 00:29:28.626008 | localhost | changed
2026-03-16 00:29:28.637943 | 
2026-03-16 00:29:28.638156 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-16 00:29:28.673004 | localhost | ok
2026-03-16 00:29:28.686094 | 
2026-03-16 00:29:28.686234 | TASK [Set zuul-log-path fact]
2026-03-16 00:29:28.706010 | localhost | ok
2026-03-16 00:29:28.722399 | 
2026-03-16 00:29:28.722527 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-16 00:29:28.751650 | localhost | ok
2026-03-16 00:29:28.760389 | 
2026-03-16 00:29:28.760479 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)]
2026-03-16 00:29:28.808216 | localhost | ok:
2026-03-16 00:29:28.808540 | localhost | {
2026-03-16 00:29:28.808596 | localhost |   "auth": {
2026-03-16 00:29:28.808748 | localhost |     "password": "IonWCwcuOFib&&<}wp^:[2G/",
2026-03-16 00:29:28.808808 | localhost |     "username": "9LF91O0RK7JU"
2026-03-16 00:29:28.808860 | localhost |   },
2026-03-16 00:29:28.808899 | localhost |   "profile": "rackspace",
2026-03-16 00:29:28.808934 | localhost |   "region_name": "ORD"
2026-03-16 00:29:28.808978 | localhost | }
2026-03-16 00:29:28.809098 | 
2026-03-16 00:29:28.821282 | 
2026-03-16 00:29:28.821404 | TASK [Run upload-logs-failover role (S3 then Swift)]
2026-03-16 00:29:28.840309 | localhost | Output suppressed because no_log was given
2026-03-16 00:29:28.855261 | 
2026-03-16 00:29:28.855359 | TASK [upload-logs-failover : Initialize control vars]
2026-03-16 00:29:28.885561 | localhost | ok
2026-03-16 00:29:28.922206 | 
2026-03-16 00:29:28.922324 | LOOP [upload-logs-failover : Perform log upload]
2026-03-16 00:29:28.946795 | localhost | Output suppressed because no_log was given
2026-03-16 00:29:28.954705 | localhost | included: /var/lib/zuul/builds/9c60709488604920bb23d71f7829947c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml
2026-03-16 00:29:28.962622 | 
2026-03-16 00:29:28.962711 | LOOP [upload-logs-failover : Unpack vars for swift upload]
2026-03-16 00:29:29.048201 | localhost | Output suppressed because no_log was given
2026-03-16 00:29:29.060718 | 
2026-03-16 00:29:29.060897 | TASK [Upload logs to swift]
2026-03-16 00:29:29.090869 | localhost | ok
2026-03-16 00:29:29.136528 | 
2026-03-16 00:29:29.136674 | TASK [Set zuul-log-path fact]
2026-03-16 00:29:29.172090 | localhost | skipping: Conditional result was False
2026-03-16 00:29:29.181345 | 
2026-03-16 00:29:29.181711 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-03-16 00:29:29.584361 | localhost -> localhost | ok: Runtime: 0:00:00.006409
2026-03-16 00:29:29.591520 | 
2026-03-16 00:29:29.591629 | TASK [upload-logs-swift : Upload logs to swift]