2026-02-20 00:06:59.116995 | Job console starting...
2026-02-20 00:06:59.130381 | Updating repositories
2026-02-20 00:06:59.247756 | Preparing job workspace
2026-02-20 00:07:03.305608 | Running Ansible setup...
2026-02-20 00:07:08.380655 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-02-20 00:07:09.103133 | 
2026-02-20 00:07:09.103282 | PLAY [localhost]
2026-02-20 00:07:09.113822 | 
2026-02-20 00:07:09.113935 | TASK [Gathering Facts]
2026-02-20 00:07:10.090064 | localhost | ok
2026-02-20 00:07:10.107391 | 
2026-02-20 00:07:10.107529 | TASK [Setup log path fact]
2026-02-20 00:07:10.126829 | localhost | ok
2026-02-20 00:07:10.139861 | 
2026-02-20 00:07:10.139934 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-20 00:07:10.168488 | localhost | ok
2026-02-20 00:07:10.180477 | 
2026-02-20 00:07:10.180625 | TASK [emit-job-header : Print job information]
2026-02-20 00:07:10.220581 | # Job Information
2026-02-20 00:07:10.220744 | Ansible Version: 2.15.12
2026-02-20 00:07:10.220776 | Job: ansible-test-sanity-docker-stable-2.10
2026-02-20 00:07:10.220796 | Pipeline: periodic
2026-02-20 00:07:10.220814 | Executor: ze02.softwarefactory-project.io
2026-02-20 00:07:10.220832 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-02-20 00:07:10.220853 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/505/ansible/5054b2d133ad4b159b9cb6b919484e58/
2026-02-20 00:07:10.220872 | Event ID: 6d539d67fcee4d5b96e508018f097453
2026-02-20 00:07:10.225728 | 
2026-02-20 00:07:10.225811 | LOOP [emit-job-header : Print node information]
2026-02-20 00:07:10.343089 | localhost | ok:
2026-02-20 00:07:10.343473 | localhost | # Node Information
2026-02-20 00:07:10.343542 | localhost | Inventory Hostname: controller
2026-02-20 00:07:10.343587 | localhost | Hostname: np0005624975
2026-02-20 00:07:10.343629 | localhost | Username: zuul
2026-02-20 00:07:10.343726 | localhost | Distro: Fedora 37
2026-02-20 00:07:10.343780 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2026-02-20 00:07:10.343821 | localhost | Region: ca-ymq-1
2026-02-20 00:07:10.343859 | localhost | Label: ansible-fedora-37-1vcpu
2026-02-20 00:07:10.343896 | localhost | Product Name: OpenStack Nova
2026-02-20 00:07:10.343935 | localhost | Interface IP: 199.204.45.233
2026-02-20 00:07:10.370611 | 
2026-02-20 00:07:10.370811 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-20 00:07:10.816692 | localhost -> localhost | changed
2026-02-20 00:07:10.824366 | 
2026-02-20 00:07:10.824493 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-20 00:07:11.847169 | localhost -> localhost | changed
2026-02-20 00:07:11.867422 | 
2026-02-20 00:07:11.867514 | PLAY [all:!appliance*]
2026-02-20 00:07:11.883252 | 
2026-02-20 00:07:11.883335 | TASK [include_role : start-zuul-console]
2026-02-20 00:07:11.902640 | controller | ok
2026-02-20 00:07:11.918627 | 
2026-02-20 00:07:11.918763 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-02-20 00:07:12.299435 | controller | ok
2026-02-20 00:07:12.319845 | 
2026-02-20 00:07:12.319964 | TASK [use-our-mirror : Retrieve the IP address]
2026-02-20 00:07:13.088023 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-02-20 00:07:13.097556 | 
2026-02-20 00:07:13.097693 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-02-20 00:07:13.633613 | controller | skipping: Conditional result was False
2026-02-20 00:07:13.640632 | 
2026-02-20 00:07:13.640785 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-02-20 00:07:13.664483 | controller | skipping: Conditional result was False
2026-02-20 00:07:13.671032 | 
2026-02-20 00:07:13.671097 | TASK [use-our-mirror : Create the podman configuration directory]
2026-02-20 00:07:13.695345 | controller | skipping: Conditional result was False
2026-02-20 00:07:13.709374 | 
2026-02-20 00:07:13.709561 | TASK [use-our-mirror : Copy the podman configuration]
2026-02-20 00:07:13.736214 | controller | skipping: Conditional result was False
2026-02-20 00:07:13.751187 | 
2026-02-20 00:07:13.751386 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-02-20 00:07:13.778580 | controller | skipping: Conditional result was False
2026-02-20 00:07:13.787896 | 
2026-02-20 00:07:13.788033 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-02-20 00:07:13.813451 | controller | skipping: Conditional result was False
2026-02-20 00:07:13.838566 | 
2026-02-20 00:07:13.838736 | TASK [Disable Fedora Modular]
2026-02-20 00:07:14.092061 | controller | changed
2026-02-20 00:07:14.106387 | 
2026-02-20 00:07:14.106562 | TASK [Enable EPEL]
2026-02-20 00:07:14.123713 | controller | skipping: Conditional result was False
2026-02-20 00:07:14.138795 | 
2026-02-20 00:07:14.138944 | TASK [Register the RHEL node]
2026-02-20 00:07:14.692895 | 
2026-02-20 00:07:14.693103 | TASK [Show the subscription-manager status]
2026-02-20 00:07:15.261970 | controller | skipping: Conditional result was False
2026-02-20 00:07:15.270701 | 
2026-02-20 00:07:15.270854 | TASK [Enable EPEL on RHEL]
2026-02-20 00:07:15.815459 | controller | skipping: Conditional result was False
2026-02-20 00:07:15.823868 | 
2026-02-20 00:07:15.823994 | TASK [Install git and tox]
2026-02-20 00:08:40.745983 | controller | changed
2026-02-20 00:08:40.765530 | 
2026-02-20 00:08:40.765720 | TASK [include_role : prepare-workspace]
2026-02-20 00:08:40.802548 | controller | ok
2026-02-20 00:08:40.834909 | 
2026-02-20 00:08:40.835024 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-20 00:08:41.121718 | controller | ok
2026-02-20 00:08:41.138823 | 
2026-02-20 00:08:41.138970 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-20 00:08:54.858422 | controller | Output suppressed because no_log was given
2026-02-20 00:08:54.878400 | 
2026-02-20 00:08:54.878578 | TASK [include_role : prepare-workspace-openshift]
2026-02-20 00:08:54.905125 | controller | skipping: Conditional result was False
2026-02-20 00:08:54.944604 | 
2026-02-20 00:08:54.944958 | PLAY [all:!appliance]
2026-02-20 00:08:55.020230 | 
2026-02-20 00:08:55.020398 | TASK [Run add-build-sshkey role (RSA)]
2026-02-20 00:08:55.061220 | controller | ok
2026-02-20 00:08:55.088368 | 
2026-02-20 00:08:55.088490 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-20 00:08:55.379166 | controller -> localhost | ok
2026-02-20 00:08:55.391311 | 
2026-02-20 00:08:55.391423 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-20 00:08:55.426168 | controller | ok
2026-02-20 00:08:55.447856 | controller | included: /var/lib/zuul/builds/5054b2d133ad4b159b9cb6b919484e58/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-20 00:08:55.456201 | 
2026-02-20 00:08:55.456295 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-20 00:08:55.954218 | controller -> localhost | Generating public/private rsa key pair.
2026-02-20 00:08:55.954485 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/5054b2d133ad4b159b9cb6b919484e58/work/5054b2d133ad4b159b9cb6b919484e58_id_rsa.
2026-02-20 00:08:55.954523 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/5054b2d133ad4b159b9cb6b919484e58/work/5054b2d133ad4b159b9cb6b919484e58_id_rsa.pub.
2026-02-20 00:08:55.954552 | controller -> localhost | The key fingerprint is:
2026-02-20 00:08:55.954578 | controller -> localhost | SHA256:QPyar+INv4LGrfglB04VBu9d0UoUQtL9u0OhMPUGq1U zuul-build-sshkey
2026-02-20 00:08:55.954602 | controller -> localhost | The key's randomart image is:
2026-02-20 00:08:55.954628 | controller -> localhost | +---[RSA 2048]----+
2026-02-20 00:08:55.954654 | controller -> localhost | |  ..+++o+o       |
2026-02-20 00:08:55.954704 | controller -> localhost | |   o +o.=.E      |
2026-02-20 00:08:55.954732 | controller -> localhost | |    o .+.B       |
2026-02-20 00:08:55.954774 | controller -> localhost | |   o .oo= =      |
2026-02-20 00:08:55.954827 | controller -> localhost | |  o . .BSo o     |
2026-02-20 00:08:55.954867 | controller -> localhost | | o .  + . o      |
2026-02-20 00:08:55.954894 | controller -> localhost | | .oo+  . . .     |
2026-02-20 00:08:55.954918 | controller -> localhost | | .++++  . o      |
2026-02-20 00:08:55.954937 | controller -> localhost | |.ooo.o=o   .     |
2026-02-20 00:08:55.954963 | controller -> localhost | +----[SHA256]-----+
2026-02-20 00:08:55.955044 | controller -> localhost | ok: Runtime: 0:00:00.085934
2026-02-20 00:08:55.963913 | 
2026-02-20 00:08:55.964022 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-20 00:08:55.998084 | controller | ok
2026-02-20 00:08:56.013415 | controller | included: /var/lib/zuul/builds/5054b2d133ad4b159b9cb6b919484e58/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-20 00:08:56.027269 | 
2026-02-20 00:08:56.027382 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-20 00:08:56.053250 | controller | skipping: Conditional result was False
2026-02-20 00:08:56.061959 | 
2026-02-20 00:08:56.062052 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-20 00:08:56.524193 | controller | changed
2026-02-20 00:08:56.535337 | 
2026-02-20 00:08:56.535476 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-20 00:08:56.751905 | controller | ok
2026-02-20 00:08:56.761981 | 
2026-02-20 00:08:56.762123 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-20 00:08:57.351947 | controller | changed
2026-02-20 00:08:57.368614 | 
2026-02-20 00:08:57.368855 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-20 00:08:57.986123 | controller | changed
2026-02-20 00:08:57.995583 | 
2026-02-20 00:08:57.995723 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-20 00:08:58.031493 | controller | skipping: Conditional result was False
2026-02-20 00:08:58.041829 | 
2026-02-20 00:08:58.042067 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-20 00:08:58.445290 | controller -> localhost | changed
2026-02-20 00:08:58.461909 | 
2026-02-20 00:08:58.462018 | TASK [add-build-sshkey : Add back temp key]
2026-02-20 00:08:58.745861 | controller -> localhost | Identity added: /var/lib/zuul/builds/5054b2d133ad4b159b9cb6b919484e58/work/5054b2d133ad4b159b9cb6b919484e58_id_rsa (zuul-build-sshkey)
2026-02-20 00:08:58.746191 | controller -> localhost | ok: Runtime: 0:00:00.009852
2026-02-20 00:08:58.760906 | 
2026-02-20 00:08:58.761049 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-20 00:08:59.100497 | controller | ok
2026-02-20 00:08:59.115802 | 
2026-02-20 00:08:59.115933 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-20 00:08:59.142498 | controller | skipping: Conditional result was False
2026-02-20 00:08:59.168413 | 
2026-02-20 00:08:59.168549 | TASK [Run add-build-sshkey role (ECDSA)]
2026-02-20 00:08:59.207275 | controller | ok
2026-02-20 00:08:59.236939 | 
2026-02-20 00:08:59.237173 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-20 00:08:59.527629 | controller -> localhost | ok
2026-02-20 00:08:59.538885 | 
2026-02-20 00:08:59.538997 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-20 00:08:59.568918 | controller | ok
2026-02-20 00:08:59.592293 | controller | included: /var/lib/zuul/builds/5054b2d133ad4b159b9cb6b919484e58/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-20 00:08:59.606617 | 
2026-02-20 00:08:59.606934 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-20 00:08:59.980103 | controller -> localhost | Generating public/private ecdsa key pair.
2026-02-20 00:08:59.980329 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/5054b2d133ad4b159b9cb6b919484e58/work/5054b2d133ad4b159b9cb6b919484e58_id_ecdsa.
2026-02-20 00:08:59.980368 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/5054b2d133ad4b159b9cb6b919484e58/work/5054b2d133ad4b159b9cb6b919484e58_id_ecdsa.pub.
2026-02-20 00:08:59.980406 | controller -> localhost | The key fingerprint is:
2026-02-20 00:08:59.980434 | controller -> localhost | SHA256:aGbq+Tce3f1Paf7KWVcn1ZH9y9MYBSNSfuCygjsHx/0 zuul-build-sshkey
2026-02-20 00:08:59.980461 | controller -> localhost | The key's randomart image is:
2026-02-20 00:08:59.980488 | controller -> localhost | +---[ECDSA 521]---+
2026-02-20 00:08:59.980513 | controller -> localhost | |          ..+ ooo|
2026-02-20 00:08:59.980538 | controller -> localhost | |           + o o=|
2026-02-20 00:08:59.980562 | controller -> localhost | |          . o ..+|
2026-02-20 00:08:59.980586 | controller -> localhost | |       + . o ....|
2026-02-20 00:08:59.980611 | controller -> localhost | |      B S o   oo=|
2026-02-20 00:08:59.980635 | controller -> localhost | |     = + o o ..==|
2026-02-20 00:08:59.980659 | controller -> localhost | |    . o o . E .o=|
2026-02-20 00:08:59.980805 | controller -> localhost | |   . . oo.   .o=.|
2026-02-20 00:08:59.980843 | controller -> localhost | |    o..o..    +o*|
2026-02-20 00:08:59.980870 | controller -> localhost | +----[SHA256]-----+
2026-02-20 00:08:59.980932 | controller -> localhost | ok: Runtime: 0:00:00.018309
2026-02-20 00:08:59.989714 | 
2026-02-20 00:08:59.989821 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-20 00:09:00.031933 | controller | ok
2026-02-20 00:09:00.043735 | controller | included: /var/lib/zuul/builds/5054b2d133ad4b159b9cb6b919484e58/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-20 00:09:00.056470 | 
2026-02-20 00:09:00.056582 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-20 00:09:00.092042 | controller | skipping: Conditional result was False
2026-02-20 00:09:00.099324 | 
2026-02-20 00:09:00.099405 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-20 00:14:11.562385 | controller | changed
2026-02-20 00:14:11.568588 | 
2026-02-20 00:14:11.568693 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-20 00:14:11.792459 | controller | ok
2026-02-20 00:14:11.802748 | 
2026-02-20 00:14:11.802891 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-20 00:14:12.432439 | controller | changed
2026-02-20 00:14:12.439297 | 
2026-02-20 00:14:12.439378 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-20 00:14:13.078890 | controller | changed
2026-02-20 00:14:13.088214 | 
2026-02-20 00:14:13.088316 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-20 00:14:13.114987 | controller | skipping: Conditional result was False
2026-02-20 00:14:13.122380 | 
2026-02-20 00:14:13.122470 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-20 00:14:13.447540 | controller -> localhost | changed
2026-02-20 00:14:13.460986 | 
2026-02-20 00:14:13.461116 | TASK [add-build-sshkey : Add back temp key]
2026-02-20 00:14:13.783340 | controller -> localhost | Identity added: /var/lib/zuul/builds/5054b2d133ad4b159b9cb6b919484e58/work/5054b2d133ad4b159b9cb6b919484e58_id_ecdsa (zuul-build-sshkey)
2026-02-20 00:14:13.783592 | controller -> localhost | ok: Runtime: 0:00:00.007854
2026-02-20 00:14:13.794080 | 
2026-02-20 00:14:13.794191 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-20 00:14:14.021464 | controller | ok
2026-02-20 00:14:14.028771 | 
2026-02-20 00:14:14.028880 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-20 00:14:14.064259 | controller | skipping: Conditional result was False
2026-02-20 00:14:14.082158 | 
2026-02-20 00:14:14.082300 | TASK [include_role : remove-zuul-sshkey]
2026-02-20 00:14:14.098445 | controller | skipping: Conditional result was False
2026-02-20 00:14:14.106520 | 
2026-02-20 00:14:14.106603 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-20 00:14:14.348603 | controller | ok: "logs"
2026-02-20 00:14:14.349465 | controller | ok: All items complete
2026-02-20 00:14:14.349543 | 
2026-02-20 00:14:14.553839 | controller | ok: "artifacts"
2026-02-20 00:14:14.752350 | controller | ok: "docs"
2026-02-20 00:14:14.774902 | 
2026-02-20 00:14:14.775063 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-20 00:14:15.031089 | controller | changed: "logs"
2026-02-20 00:14:15.233089 | controller | changed: "artifacts"
2026-02-20 00:14:15.436536 | controller | changed: "docs"
2026-02-20 00:14:15.508653 | 
2026-02-20 00:14:15.508773 | PLAY RECAP
2026-02-20 00:14:15.509003 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-02-20 00:14:15.509029 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-20 00:14:15.509048 | 
2026-02-20 00:14:15.633325 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-02-20 00:14:15.635557 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-02-20 00:14:16.368776 | 
2026-02-20 00:14:16.368888 | PLAY [all]
2026-02-20 00:14:16.398709 | 
2026-02-20 00:14:16.399736 | TASK [Install binary dependencies]
2026-02-20 00:14:16.485184 | controller | ok
2026-02-20 00:14:16.513139 | 
2026-02-20 00:14:16.513284 | TASK [bindep : Include find tasks]
2026-02-20 00:14:16.544390 | controller | ok
2026-02-20 00:14:16.583053 | controller | included: /var/lib/zuul/builds/5054b2d133ad4b159b9cb6b919484e58/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-02-20 00:14:16.601237 | 
2026-02-20 00:14:16.601360 | TASK [bindep : Look for bindep.txt]
2026-02-20 00:14:17.190730 | controller | ok
2026-02-20 00:14:17.200308 | 
2026-02-20 00:14:17.200453 | TASK [bindep : Define bindep_file fact]
2026-02-20 00:14:17.227328 | controller | skipping: Conditional result was False
2026-02-20 00:14:17.237072 | 
2026-02-20 00:14:17.237210 | TASK [bindep : Look for other-requirements.txt]
2026-02-20 00:14:17.457769 | controller | ok
2026-02-20 00:14:17.465012 | 
2026-02-20 00:14:17.465107 | TASK [bindep : Define bindep_file fact]
2026-02-20 00:14:17.497747 | controller | skipping: Conditional result was False
2026-02-20 00:14:17.505625 | 
2026-02-20 00:14:17.505775 | TASK [bindep : Look for bindep fallback file]
2026-02-20 00:14:17.551079 | controller | skipping: Conditional result was False
2026-02-20 00:14:17.559448 | 
2026-02-20 00:14:17.559570 | TASK [bindep : Define bindep_file fact]
2026-02-20 00:14:17.584360 | controller | skipping: Conditional result was False
2026-02-20 00:14:17.591257 | 
2026-02-20 00:14:17.591372 | TASK [bindep : Include bindep tasks]
2026-02-20 00:14:17.616320 | controller | skipping: Conditional result was False
2026-02-20 00:14:17.622915 | 
2026-02-20 00:14:17.623254 | TASK [bindep : Include install tasks]
2026-02-20 00:14:17.648940 | controller | skipping: Conditional result was False
2026-02-20 00:14:17.656527 | 
2026-02-20 00:14:17.656645 | LOOP [bindep : Include package tasks]
2026-02-20 00:14:17.715287 | 
2026-02-20 00:14:17.715516 | TASK [Run test-setup role]
2026-02-20 00:14:17.742304 | controller | ok
2026-02-20 00:14:17.765718 | 
2026-02-20 00:14:17.765880 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-02-20 00:14:17.972511 | controller | ok
2026-02-20 00:14:17.983613 | 
2026-02-20 00:14:17.983813 | TASK [test-setup : Run tools/test-setup.sh]
2026-02-20 00:14:18.536937 | controller | skipping: Conditional result was False
2026-02-20 00:14:18.587495 | 
2026-02-20 00:14:18.587636 | PLAY RECAP
2026-02-20 00:14:18.587744 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-02-20 00:14:18.587782 | 
2026-02-20 00:14:18.767970 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-02-20 00:14:18.768863 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-02-20 00:14:21.774633 | 
2026-02-20 00:14:21.775107 | PLAY [controller]
2026-02-20 00:14:21.809417 | 
2026-02-20 00:14:21.809558 | TASK [Create the /root directory]
2026-02-20 00:14:22.434136 | controller | ok
2026-02-20 00:14:22.447130 | 
2026-02-20 00:14:22.447321 | TASK [Install glibc-langpack-en]
2026-02-20 00:14:26.426903 | controller | ok: Nothing to do
2026-02-20 00:14:26.436812 | 
2026-02-20 00:14:26.436926 | TASK [Ensure controller directory exists]
2026-02-20 00:14:26.644858 | controller | changed
2026-02-20 00:14:26.654062 | 
2026-02-20 00:14:26.654175 | TASK [Install container runtime]
2026-02-20 00:14:26.729074 | controller | ok
2026-02-20 00:14:26.780164 | 
2026-02-20 00:14:26.780293 | LOOP [ensure-docker : Gather variables for each operating system]
2026-02-20 00:14:26.812787 | controller | ok: "/var/lib/zuul/builds/5054b2d133ad4b159b9cb6b919484e58/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2026-02-20 00:14:26.823143 | 
2026-02-20 00:14:26.823288 | TASK [ensure-docker : Sanity check for distro selection]
2026-02-20 00:14:26.838796 | controller | skipping: Conditional result was False
2026-02-20 00:14:26.847369 | 
2026-02-20 00:14:26.847503 | TASK [ensure-docker : Sanity check for distro install]
2026-02-20 00:14:26.871978 | controller | skipping: Conditional result was False
2026-02-20 00:14:26.878963 | 
2026-02-20 00:14:26.879056 | TASK [ensure-docker : Sanity check for upstream install]
2026-02-20 00:14:26.903727 | controller | skipping: Conditional result was False
2026-02-20 00:14:26.910459 | 
2026-02-20 00:14:26.910608 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2026-02-20 00:14:26.935056 | controller | skipping: Conditional result was False
2026-02-20 00:14:26.942908 | 
2026-02-20 00:14:26.943046 | TASK [ensure-docker : Include tasks for docker install]
2026-02-20 00:14:26.963862 | controller | ok
2026-02-20 00:14:26.974133 | controller | included: /var/lib/zuul/builds/5054b2d133ad4b159b9cb6b919484e58/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2026-02-20 00:14:26.985091 | 
2026-02-20 00:14:26.985243 | TASK [ensure-docker : Not Implemented]
2026-02-20 00:14:27.000516 | controller | skipping: Conditional result was False
2026-02-20 00:14:27.007557 | 
2026-02-20 00:14:27.007660 | TASK [Set up docker mirrors]
2026-02-20 00:14:27.027602 | controller | ok
2026-02-20 00:14:27.053425 | 
2026-02-20 00:14:27.053588 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2026-02-20 00:14:27.088942 | controller | skipping: Conditional result was False
2026-02-20 00:14:27.099704 | 
2026-02-20 00:14:27.099869 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2026-02-20 00:14:27.135713 | controller | skipping: Conditional result was False
2026-02-20 00:14:27.142050 | 
2026-02-20 00:14:27.142146 | TASK [use-docker-mirror : Set docker_mirror fact]
2026-02-20 00:14:27.177099 | controller | skipping: Conditional result was False
2026-02-20 00:14:27.186459 | 
2026-02-20 00:14:27.186586 | TASK [use-docker-mirror : Set up docker mirrors]
2026-02-20 00:14:27.211716 | controller | skipping: Conditional result was False
2026-02-20 00:14:27.233991 | 
2026-02-20 00:14:27.234129 | TASK [Add all repositories]
2026-02-20 00:14:27.258627 | controller | ok
2026-02-20 00:14:27.305954 | 
2026-02-20 00:14:27.306110 | LOOP [ensure-package-repositories : Install repositories]
2026-02-20 00:14:27.340094 | controller | ok: "/var/lib/zuul/builds/5054b2d133ad4b159b9cb6b919484e58/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2026-02-20 00:14:27.340343 | controller | ok: All items complete
2026-02-20 00:14:27.340371 | 
2026-02-20 00:14:27.388361 | controller | included: /var/lib/zuul/builds/5054b2d133ad4b159b9cb6b919484e58/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2026-02-20 00:14:27.395957 | 
2026-02-20 00:14:27.396090 | TASK [ensure-package-repositories : Create GPG key temp directory]
2026-02-20 00:14:27.750993 | controller | changed
2026-02-20 00:14:27.766535 | 
2026-02-20 00:14:27.766815 | LOOP [ensure-package-repositories : Upload GPG keys]
2026-02-20 00:14:28.821208 | controller | changed:
2026-02-20 00:14:28.821423 | controller | {
2026-02-20 00:14:28.821462 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2026-02-20 00:14:28.821498 | controller | }
2026-02-20 00:14:28.831115 | 
2026-02-20 00:14:28.831401 | LOOP [ensure-package-repositories : Import GPG keys]
2026-02-20 00:14:29.466827 | controller | changed:
2026-02-20 00:14:29.467166 | controller | {
2026-02-20 00:14:29.467226 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2026-02-20 00:14:29.467275 | controller | }
2026-02-20 00:14:29.467357 | controller | changed: All items complete
2026-02-20 00:14:29.467415 | 
2026-02-20 00:14:29.480438 | 
2026-02-20 00:14:29.480590 | TASK [ensure-package-repositories : Remove GPG temp directory]
2026-02-20 00:14:29.713906 | controller | changed
2026-02-20 00:14:29.729827 | 
2026-02-20 00:14:29.729938 | LOOP [ensure-package-repositories : Add repositories]
2026-02-20 00:14:30.112944 | controller | changed:
2026-02-20 00:14:30.113298 | controller | {
2026-02-20 00:14:30.113356 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2026-02-20 00:14:30.113400 | controller |   "description": "Docker CE Stable - $basearch",
2026-02-20 00:14:30.113438 | controller |   "gpgcheck": true,
2026-02-20 00:14:30.113475 | controller |   "name": "docker-ce-stable"
2026-02-20 00:14:30.113515 | controller | }
2026-02-20 00:14:30.136739 | 
2026-02-20 00:14:30.136848 | TASK [ensure-docker : Install pre-reqs]
2026-02-20 00:14:32.494149 | controller | ok: Nothing to do
2026-02-20 00:14:32.508991 | 
2026-02-20 00:14:32.509161 | TASK [ensure-docker : Remove packages]
2026-02-20 00:14:33.872956 | controller | ok: Nothing to do
2026-02-20 00:14:33.889002 | 
2026-02-20 00:14:33.889124 | TASK [ensure-docker : Install upstream docker using package]
2026-02-20 00:15:20.447175 | controller | changed
2026-02-20 00:15:20.456260 | 
2026-02-20 00:15:20.456395 | TASK [ensure-docker : Setup docker]
2026-02-20 00:15:20.480238 | controller | ok
2026-02-20 00:15:20.497625 | controller | included: /var/lib/zuul/builds/5054b2d133ad4b159b9cb6b919484e58/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2026-02-20 00:15:20.514467 | 
2026-02-20 00:15:20.514579 | TASK [ensure-docker : Ensure "docker" group exists]
2026-02-20 00:15:20.940157 | controller | ok
2026-02-20 00:15:20.948826 | 
2026-02-20 00:15:20.948899 | TASK [ensure-docker : Add user to docker group]
2026-02-20 00:15:21.499705 | controller | changed
2026-02-20 00:15:21.517486 | 
2026-02-20 00:15:21.517915 | TASK [Update docker daemon proxy configuration]
2026-02-20 00:15:21.555251 | controller | skipping: Conditional result was False
2026-02-20 00:15:21.565234 | 
2026-02-20 00:15:21.565328 | TASK [Lower default MTU]
2026-02-20 00:15:21.589951 | controller | skipping: Conditional result was False
2026-02-20 00:15:21.599284 | 
2026-02-20 00:15:21.599359 | TASK [ensure-docker : Get the status of the docket.socket unit]
2026-02-20 00:15:21.991330 | controller | inactive
2026-02-20 00:15:22.166465 | controller | ok: Runtime: 0:00:00.013159
2026-02-20 00:15:22.183227 | 
2026-02-20 00:15:22.184127 | TASK [ensure-docker : Get the status of the docket unit]
2026-02-20 00:15:22.449716 | controller | inactive
2026-02-20 00:15:22.738913 | controller | ok: Runtime: 0:00:00.012383
2026-02-20 00:15:22.754170 | 
2026-02-20 00:15:22.754352 | TASK [ensure-docker : Restart docker]
2026-02-20 00:15:22.799722 | Trigger a Docker restart
2026-02-20 00:15:22.805607 | 
2026-02-20 00:15:22.805731 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2026-02-20 00:15:22.834770 | 
2026-02-20 00:15:22.834902 | TASK [ensure-docker : Install docker-compose]
2026-02-20 00:15:22.849268 | controller | skipping: Conditional result was False
2026-02-20 00:15:22.856435 | 
2026-02-20 00:15:22.856523 | TASK [ensure-docker : Flush handlers before role exit]
2026-02-20 00:15:22.868273 | 
2026-02-20 00:15:22.868376 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2026-02-20 00:15:24.062436 | controller | changed
2026-02-20 00:15:24.072582 | 
2026-02-20 00:15:24.072682 | TASK [ensure-docker : Assure docker service is running]
2026-02-20 00:15:25.813447 | controller | changed
2026-02-20 00:15:25.824003 | 
2026-02-20 00:15:25.824091 | TASK [ensure-docker : Assure docker.socket service is running]
2026-02-20 00:15:27.862112 | controller | ok
2026-02-20 00:15:27.879540 | 
2026-02-20 00:15:27.879710 | TASK [ensure-docker : Correct group ownership on docker sock]
2026-02-20 00:15:28.476714 | controller | ok
2026-02-20 00:15:28.483308 | 
2026-02-20 00:15:28.483376 | TASK [ensure-docker : Validate ability to talk with docker]
2026-02-20 00:15:29.036598 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2026-02-20 00:15:29.520945 | controller | ok: Runtime: 0:00:00.046718
2026-02-20 00:15:29.526948 | 
2026-02-20 00:15:29.527018 | TASK [ensure-docker : Get version details]
2026-02-20 00:15:30.112493 | controller | Client: Docker Engine - Community
2026-02-20 00:15:30.112620 | controller |  Version:           24.0.7
2026-02-20 00:15:30.112656 | controller |  API version:       1.43
2026-02-20 00:15:30.112706 | controller |  Go version:        go1.20.10
2026-02-20 00:15:30.112745 | controller |  Git commit:        afdd53b
2026-02-20 00:15:30.112794 | controller |  Built:             Thu Oct 26 09:09:39 2023
2026-02-20 00:15:30.112837 | controller |  OS/Arch:           linux/amd64
2026-02-20 00:15:30.112892 | controller |  Context:           default
2026-02-20 00:15:30.112908 | controller |
2026-02-20 00:15:30.112953 | controller | Server: Docker Engine - Community
2026-02-20 00:15:30.112985 | controller |  Engine:
2026-02-20 00:15:30.113106 | controller |   Version:          24.0.7
2026-02-20 00:15:30.113139 | controller |   API version:      1.43 (minimum version 1.12)
2026-02-20 00:15:30.113179 | controller |   Go version:       go1.20.10
2026-02-20 00:15:30.113209 | controller |   Git commit:       311b9ff
2026-02-20 00:15:30.113255 | controller |   Built:            Thu Oct 26 09:08:11 2023
2026-02-20 00:15:30.113291 | controller |   OS/Arch:          linux/amd64
2026-02-20 00:15:30.113332 | controller |   Experimental:     false
2026-02-20 00:15:30.113351 | controller |  containerd:
2026-02-20 00:15:30.113437 | controller |   Version:          1.6.25
2026-02-20 00:15:30.113480 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2026-02-20 00:15:30.113499 | controller |  runc:
2026-02-20 00:15:30.113603 | controller |   Version:          1.1.10
2026-02-20 00:15:30.113647 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2026-02-20 00:15:30.113682 | controller |  docker-init:
2026-02-20 00:15:30.113742 | controller |   Version:          0.19.0
2026-02-20 00:15:30.113785 | controller |   GitCommit:        de40ad0
2026-02-20 00:15:30.565005 | controller | ok: Runtime: 0:00:00.084316
2026-02-20 00:15:30.574157 | 
2026-02-20 00:15:30.574271 | TASK [ensure-docker : Dump installed docker details]
2026-02-20 00:15:30.608290 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version:           24.0.7\n API version:       1.43\n Go version:        go1.20.10\n Git commit:        afdd53b\n Built:             Thu Oct 26 09:09:39 2023\n OS/Arch:           linux/amd64\n Context:           default\n\nServer: Docker Engine - Community\n Engine:\n  Version:          24.0.7\n  API version:      1.43 (minimum version 1.12)\n  Go version:       go1.20.10\n  Git commit:       311b9ff\n  Built:            Thu Oct 26 09:08:11 2023\n  OS/Arch:          linux/amd64\n  Experimental:     false\n containerd:\n  Version:          1.6.25\n  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n  Version:          1.1.10\n  GitCommit:        v1.1.10-0-g18a0cb0\n docker-init:\n  Version:          0.19.0\n  GitCommit:        de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2026-02-20 00:15:30.031282', 'end': '2026-02-20 00:15:30.115598', 'delta': '0:00:00.084316', 'msg': '', 'zuul_log_id': 'fa163efc-24cc-b2a3-fd6f-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version:           24.0.7', ' API version:       1.43', ' Go version:        go1.20.10', ' Git commit:        afdd53b', ' Built:             Thu Oct 26 09:09:39 2023', ' OS/Arch:           linux/amd64', ' Context:           default', '', 'Server: Docker Engine - Community', ' Engine:', '  Version:          24.0.7', '  API version:      1.43 (minimum version 1.12)', '  Go version:       go1.20.10', '  Git commit:       311b9ff', '  Built:            Thu Oct 26 09:08:11 2023', '  OS/Arch:          linux/amd64', '  Experimental:     false', ' containerd:', '  Version:          1.6.25', '  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', '  Version:          1.1.10', '  GitCommit:        v1.1.10-0-g18a0cb0', ' docker-init:', '  Version:          0.19.0', '  GitCommit:        de40ad0'], 'stderr_lines': [], 'failed': False}
2026-02-20 00:15:30.621378 | 
2026-02-20 00:15:30.621493 | TASK [Use buildset registry]
2026-02-20 00:15:30.649821 | controller | skipping: Conditional result was False
2026-02-20 00:15:30.666961 | 
2026-02-20 00:15:30.667053 | TASK [Ensure python3.8 is present]
2026-02-20 00:15:30.682006 | controller | skipping: Conditional result was False
2026-02-20 00:15:30.691737 | 
2026-02-20 00:15:30.691821 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-02-20 00:15:30.733344 | controller | ok
2026-02-20 00:15:30.768352 | 
2026-02-20 00:15:30.768498 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-02-20 00:15:33.144964 | controller | ok: Nothing to do
2026-02-20 00:15:33.150655 | 
2026-02-20 00:15:33.150759 | TASK [our-ensure-python : Also install python3-devel]
2026-02-20 00:15:45.890514 | controller | changed
2026-02-20 00:15:45.910200 | 
2026-02-20 00:15:45.910400 | TASK [Run ensure-virtualenv role]
2026-02-20 00:15:45.944241 | controller | ok
2026-02-20 00:15:45.997499 | 
2026-02-20 00:15:45.997646 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-02-20 00:15:46.233599 | controller | /usr/bin/virtualenv
2026-02-20 00:15:46.545602 | controller | ok: Runtime: 0:00:00.004910
2026-02-20 00:15:46.555581 | 
2026-02-20 00:15:46.555744 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-02-20 00:15:46.592525 | controller | skipping: Conditional result was False
2026-02-20 00:15:46.593039 | controller | changed: All items complete
2026-02-20 00:15:46.593128 | 
2026-02-20 00:15:46.620617 | 
2026-02-20 00:15:46.620859 | TASK [Find the full path of the Python interpreter]
2026-02-20 00:15:46.853425 | controller | /usr/bin/python3.8
2026-02-20 00:15:47.204926 | controller | ok
2026-02-20 00:15:47.221171 | 
2026-02-20 00:15:47.221366 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-02-20 00:15:48.788202 | controller | created virtual environment CPython3.8.18.final.0-64 in 742ms
2026-02-20 00:15:48.804596 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-02-20 00:15:48.804623 | controller |   seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv)
2026-02-20 00:15:48.804635 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-02-20 00:15:48.804651 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-02-20 00:15:49.305231 | controller | changed
2026-02-20 00:15:49.321201 | 
2026-02-20 00:15:49.321387 | TASK [Set selinux package]
2026-02-20 00:15:49.371007 | controller | ok
2026-02-20 00:15:49.379880 | 
2026-02-20 00:15:49.380024 | TASK [Set selinux package (Fedora)]
2026-02-20 00:15:49.425493 | controller | ok
2026-02-20 00:15:49.437799 | 
2026-02-20 00:15:49.437964 | TASK [Install selinux into virtualenv]
2026-02-20 00:15:52.507360 | controller | Collecting selinux-please-lie-to-me
2026-02-20 00:15:52.596033 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-02-20 00:15:53.484170 | controller | Collecting setuptools<50.0.0
2026-02-20 00:15:53.498164 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-02-20 00:15:53.551394 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 16.5 MB/s eta 0:00:00
2026-02-20 00:15:53.719978 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-02-20 00:15:53.722343 | controller |   Attempting uninstall: setuptools
2026-02-20 00:15:53.728428 | controller |     Found existing installation: setuptools 62.6.0
2026-02-20 00:15:53.973456 | controller |     Uninstalling setuptools-62.6.0:
2026-02-20 00:15:53.996548 | controller |       Successfully uninstalled setuptools-62.6.0
2026-02-20 00:15:54.858775 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-02-20 00:15:55.137626 | controller |
2026-02-20 00:15:55.334185 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-02-20 00:15:55.334221 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-02-20 00:15:55.494649 | controller | ok: Runtime: 0:00:05.497398
2026-02-20 00:15:55.512525 | 
2026-02-20 00:15:55.512748 | TASK [Install pytest-forked into virtualenv]
2026-02-20 00:15:56.595171 | controller | Collecting pytest-forked
2026-02-20 00:15:56.673898 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-02-20 00:15:56.762689 | controller | Collecting py
2026-02-20 00:15:56.774868 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-02-20 00:15:56.807065 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 3.3 MB/s eta 0:00:00
2026-02-20 00:15:57.108119 | controller | Collecting pytest>=3.10
2026-02-20 00:15:57.118046 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2026-02-20 00:15:57.135530 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 22.8 MB/s eta 0:00:00
2026-02-20 00:15:57.231287 | controller | Collecting packaging
2026-02-20 00:15:57.234775 | controller |   Downloading packaging-26.0-py3-none-any.whl (74 kB)
2026-02-20 00:15:57.241841 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 12.7 MB/s eta 0:00:00
2026-02-20 00:15:57.271225 | controller | Collecting exceptiongroup>=1.0.0rc8
2026-02-20 00:15:57.274775 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2026-02-20 00:15:57.310692 | controller | Collecting pluggy<2,>=1.5
2026-02-20 00:15:57.317260 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2026-02-20 00:15:57.375424 | controller | Collecting tomli>=1
2026-02-20 00:15:57.378746 | controller |   Downloading tomli-2.4.0-py3-none-any.whl (14 kB)
2026-02-20 00:15:57.401502 | controller | Collecting iniconfig
2026-02-20 00:15:57.405884 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2026-02-20 00:15:57.460909 | controller | Collecting typing-extensions>=4.6.0
2026-02-20 00:15:57.464812 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2026-02-20 00:15:57.471307 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 10.4 MB/s eta 0:00:00
2026-02-20 00:15:57.601164 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2026-02-20 00:15:58.664383 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-26.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.4.0 typing-extensions-4.13.2
2026-02-20 00:15:58.675043 | controller |
2026-02-20 00:15:58.866180 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-02-20 00:15:58.866194 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-02-20 00:15:59.075330 | controller | ok: Runtime: 0:00:02.869205
2026-02-20 00:15:59.089831 | 
2026-02-20 00:15:59.090023 | TASK [Update pip]
2026-02-20 00:16:00.005117 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2026-02-20 00:16:00.353012 | controller | Collecting pip
2026-02-20 00:16:00.438884 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2026-02-20 00:16:00.507747 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 28.2 MB/s eta 0:00:00
2026-02-20 00:16:00.659564 | controller | Installing collected packages: pip
2026-02-20 00:16:00.664006 | controller |   Attempting uninstall: pip
2026-02-20 00:16:00.666551 | controller |     Found existing installation: pip 22.2.2
2026-02-20 00:16:01.183158 | controller |     Uninstalling pip-22.2.2:
2026-02-20 00:16:01.231541 | controller |       Successfully uninstalled pip-22.2.2
2026-02-20 00:16:03.156137 | controller | Successfully installed pip-25.0.1
2026-02-20 00:16:03.680983 | controller | ok: Runtime: 0:00:03.964021
2026-02-20 00:16:03.688991 | 
2026-02-20 00:16:03.689121 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-02-20 00:16:04.252374 | controller | changed
2026-02-20 00:16:04.259722 | 
2026-02-20 00:16:04.259814 | TASK [Install ansible into virtualenv]
2026-02-20 00:16:05.524087 | controller | Processing ./src/github.com/ansible/ansible
2026-02-20 00:16:05.532891 | controller |   Preparing metadata (setup.py): started
2026-02-20 00:20:51.918092 | [Zuul] Log Stream did not terminate
2026-02-20 00:20:51.919920 | controller | ERROR
2026-02-20 00:20:51.920034 | controller | {
2026-02-20 00:20:51.920065 | controller |   "msg": "Data could not be sent to remote host \"199.204.45.233\". Make sure this host can be reached over ssh: ssh: connect to host 199.204.45.233 port 22: No route to host\r\n",
2026-02-20 00:20:51.920091 | controller |   "unreachable": true
2026-02-20 00:20:51.920108 | controller | }
unreachable
2026-02-20 00:20:51.921467 | 
2026-02-20 00:20:51.921518 | PLAY RECAP
2026-02-20 00:20:51.921564 | controller | ok: 38 changed: 21 unreachable: 1 failed: 0 skipped: 15 rescued: 0 ignored: 0
2026-02-20 00:20:51.921587 | 
2026-02-20 00:20:52.078484 | PRE-RUN END RESULT_UNREACHABLE: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-02-20 00:20:52.080547 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-02-20 00:20:52.740215 | 
2026-02-20 00:20:52.740328 | PLAY [all]
2026-02-20 00:20:52.762693 | 
2026-02-20 00:20:52.762799 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-02-20 00:21:08.556244 | controller | changed: non-zero return code
2026-02-20 00:21:08.565043 | 
2026-02-20 00:21:08.565160 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-02-20 00:21:08.590169 | controller | skipping: Conditional result was False
2026-02-20 00:21:08.596951 | 
2026-02-20 00:21:08.597056 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-02-20 00:21:08.619130 | 
2026-02-20 00:21:08.619304 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-02-20 00:21:08.640379 | 
2026-02-20 00:21:08.640547 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-02-20 00:21:08.654947 | controller | skipping: Conditional result was False
2026-02-20 00:21:08.667351 | 
2026-02-20 00:21:08.667510 | LOOP [fetch-subunit-output : Generate subunit file]
2026-02-20 00:21:08.689367 | 
2026-02-20 00:21:08.689531 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-02-20 00:21:08.703163 | controller | skipping: Conditional result was False
2026-02-20 00:21:08.710603 | 
2026-02-20 00:21:08.710741 | TASK [fetch-subunit-output : Remove the temporary file]
2026-02-20 00:21:08.724725 | controller | skipping: Conditional result was False
2026-02-20 00:21:08.733137 | 
2026-02-20 00:21:08.733228 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-02-20 00:21:08.747642 | controller | skipping: Conditional result was False
2026-02-20 00:21:08.777458 | 
2026-02-20 00:21:08.777548 | PLAY RECAP
2026-02-20 00:21:08.777586 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-02-20 00:21:08.777606 | 
2026-02-20 00:21:08.887566 | POST-RUN END RESULT_UNREACHABLE: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-02-20 00:21:08.889580 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-02-20 00:21:09.536441 | 
2026-02-20 00:21:09.536566 | PLAY [all:!appliance*]
2026-02-20 00:21:09.561006 | 
2026-02-20 00:21:09.561153 | TASK [unregister the node]
2026-02-20 00:21:10.096605 | controller | skipping: Conditional result was False
2026-02-20 00:21:10.110110 | 
2026-02-20 00:21:10.110310 | TASK [include_role : fetch-output]
2026-02-20 00:21:10.151855 | controller | ok
2026-02-20 00:21:10.197820 | 
2026-02-20 00:21:10.197979 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-20 00:21:10.275273 | controller | skipping: Conditional result was False
2026-02-20 00:21:10.282546 | 
2026-02-20 00:21:10.282699 | TASK [fetch-output : Set log path for single node]
2026-02-20 00:21:10.322976 | controller | ok
2026-02-20 00:21:10.329079 | 
2026-02-20 00:21:10.329170 | LOOP [fetch-output : Ensure local output dirs]
2026-02-20 00:21:10.794533 | controller -> localhost | ok: "/var/lib/zuul/builds/5054b2d133ad4b159b9cb6b919484e58/work/logs"
2026-02-20 00:21:11.044779 | controller -> localhost | changed: "/var/lib/zuul/builds/5054b2d133ad4b159b9cb6b919484e58/work/artifacts"
2026-02-20 00:21:11.285858 | controller -> localhost | changed: "/var/lib/zuul/builds/5054b2d133ad4b159b9cb6b919484e58/work/docs"
2026-02-20 00:21:11.301015 | 
2026-02-20 00:21:11.301239 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-20 00:21:12.043892 | controller | changed:
2026-02-20 00:21:12.044129 | controller | .d..t...... ./
2026-02-20 00:21:12.044171 | controller | cd+++++++++ controller/
2026-02-20 00:21:12.044218 | controller | changed: All items complete
2026-02-20 00:21:12.044249 | 
2026-02-20 00:21:12.555774 | controller | changed: .d..t...... ./
2026-02-20 00:21:13.078014 | controller | changed: .d..t...... ./
2026-02-20 00:21:13.099331 | 
2026-02-20 00:21:13.099574 | TASK [include_role : fetch-output-openshift]
2026-02-20 00:21:13.123720 | controller | skipping: Conditional result was False
2026-02-20 00:21:13.133757 | 
2026-02-20 00:21:13.133866 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-20 00:21:13.167599 | controller | skipping: Conditional result was False
2026-02-20 00:21:13.181144 | controller | skipping: Conditional result was False
2026-02-20 00:21:13.231115 | 
2026-02-20 00:21:13.231244 | PLAY [localhost]
2026-02-20 00:21:13.251564 | 
2026-02-20 00:21:13.251726 | TASK [Run Zuul manifest role]
2026-02-20 00:21:13.273489 | localhost | ok
2026-02-20 00:21:13.294062 | 
2026-02-20 00:21:13.294184 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-20 00:21:13.705109 | localhost | changed
2026-02-20 00:21:13.711357 | 
2026-02-20 00:21:13.711433 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-20 00:21:13.743106 | localhost | ok
2026-02-20 00:21:13.756286 | 
2026-02-20 00:21:13.756396 | TASK [Set zuul-log-path fact]
2026-02-20 00:21:13.777624 | localhost | ok
2026-02-20 00:21:13.798213 | 
2026-02-20 00:21:13.798345 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-20 00:21:13.829881 | localhost | ok
2026-02-20 00:21:13.842179 | 
2026-02-20 00:21:13.842287 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)]
2026-02-20 00:21:13.868336 | localhost | ok:
2026-02-20 00:21:13.868627 | localhost | {
2026-02-20 00:21:13.868658 | localhost |   "auth": {
2026-02-20 00:21:13.868699 | localhost |     "password": "IonWCwcuOFib&&<}wp^:[2G/",
2026-02-20 00:21:13.868719 | localhost |     "username": "9LF91O0RK7JU"
2026-02-20 00:21:13.868736 | localhost |   },
2026-02-20 00:21:13.868753 | localhost |   "profile": "rackspace",
2026-02-20 00:21:13.868770 | localhost |   "project_name": "1251317",
2026-02-20 00:21:13.868786 | localhost |   "region_name": "DFW"
2026-02-20 00:21:13.868802 | localhost | }
2026-02-20 00:21:13.868833 | 
2026-02-20 00:21:13.884911 | 
2026-02-20 00:21:13.885147 | TASK [Run upload-logs-failover role (S3 then Swift)]
2026-02-20 00:21:13.905192 | localhost | Output suppressed because no_log was given
2026-02-20 00:21:13.920139 | 
2026-02-20 00:21:13.920246 | TASK [upload-logs-failover : Initialize control vars]
2026-02-20 00:21:13.941175 | localhost | ok
2026-02-20 00:21:13.947343 | 
2026-02-20 00:21:13.947428 | LOOP [upload-logs-failover : Perform log upload]
2026-02-20 00:21:13.986249 | localhost | Output suppressed because no_log was given
2026-02-20 00:21:14.034893 | localhost | included: /var/lib/zuul/builds/5054b2d133ad4b159b9cb6b919484e58/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml
2026-02-20 00:21:14.045048 | 
2026-02-20 00:21:14.045162 | LOOP [upload-logs-failover : Unpack vars for swift upload]
2026-02-20 00:21:14.143248 | localhost | Output suppressed because no_log was given
2026-02-20 00:21:14.150914 | 
2026-02-20 00:21:14.151021 | TASK [Upload logs to swift]
2026-02-20 00:21:14.175039 | localhost | ok
2026-02-20 00:21:14.216230 | 
2026-02-20 00:21:14.216357 | TASK [Set zuul-log-path fact]
2026-02-20 00:21:14.241913 | localhost | skipping: Conditional result was False
2026-02-20 00:21:14.250450 | 
2026-02-20 00:21:14.250567 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-02-20 00:21:14.624760 | localhost -> localhost | ok: Runtime: 0:00:00.004789
2026-02-20 00:21:14.631472 | 
2026-02-20 00:21:14.631590 | TASK [upload-logs-swift : Upload logs to swift]