2026-01-18 00:21:12.002570 | Job console starting...
2026-01-18 00:21:12.015467 | Updating repositories
2026-01-18 00:21:12.163784 | Preparing job workspace
2026-01-18 00:21:16.828371 | Running Ansible setup...
2026-01-18 00:21:22.995358 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-18 00:21:23.691507 | 
2026-01-18 00:21:23.691638 | PLAY [localhost]
2026-01-18 00:21:23.703391 | 
2026-01-18 00:21:23.703518 | TASK [Gathering Facts]
2026-01-18 00:21:24.685951 | localhost | ok
2026-01-18 00:21:24.705228 | 
2026-01-18 00:21:24.705399 | TASK [Setup log path fact]
2026-01-18 00:21:24.748144 | localhost | ok
2026-01-18 00:21:24.769797 | 
2026-01-18 00:21:24.769980 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-18 00:21:24.810613 | localhost | ok
2026-01-18 00:21:24.822317 | 
2026-01-18 00:21:24.822528 | TASK [emit-job-header : Print job information]
2026-01-18 00:21:24.862922 | # Job Information
2026-01-18 00:21:24.863154 | Ansible Version: 2.15.12
2026-01-18 00:21:24.863194 | Job: ansible-test-sanity-docker-milestone
2026-01-18 00:21:24.863218 | Pipeline: periodic
2026-01-18 00:21:24.863239 | Executor: ze04.softwarefactory-project.io
2026-01-18 00:21:24.863259 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-01-18 00:21:24.863284 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/fed/ansible/fed7acce2a2f4bd7b09c348625becd21/
2026-01-18 00:21:24.863305 | Event ID: 9ecfb4d48ef5425f8ac323d52d7bd141
2026-01-18 00:21:24.867888 | 
2026-01-18 00:21:24.867982 | LOOP [emit-job-header : Print node information]
2026-01-18 00:21:24.981242 | localhost | ok:
2026-01-18 00:21:24.981511 | localhost | # Node Information
2026-01-18 00:21:24.981540 | localhost | Inventory Hostname: controller
2026-01-18 00:21:24.981562 | localhost | Hostname: np0005586998
2026-01-18 00:21:24.981583 | localhost | Username: zuul
2026-01-18 00:21:24.981610 | localhost | Distro: Fedora 37
2026-01-18 00:21:24.981630 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2026-01-18 00:21:24.981650 | localhost | Region: ca-ymq-1
2026-01-18 00:21:24.981668 | localhost | Label: ansible-fedora-37-1vcpu
2026-01-18 00:21:24.981711 | localhost | Product Name: OpenStack Nova
2026-01-18 00:21:24.981732 | localhost | Interface IP: 162.253.55.218
2026-01-18 00:21:24.993268 | 
2026-01-18 00:21:24.993365 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-18 00:21:25.510740 | localhost -> localhost | changed
2026-01-18 00:21:25.519286 | 
2026-01-18 00:21:25.519428 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-18 00:21:26.503378 | localhost -> localhost | changed
2026-01-18 00:21:26.533846 | 
2026-01-18 00:21:26.534074 | PLAY [all:!appliance*]
2026-01-18 00:21:26.553926 | 
2026-01-18 00:21:26.554113 | TASK [include_role : start-zuul-console]
2026-01-18 00:21:26.576755 | controller | ok
2026-01-18 00:21:26.594914 | 
2026-01-18 00:21:26.595117 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-18 00:21:26.967112 | controller | ok
2026-01-18 00:21:26.983003 | 
2026-01-18 00:21:26.983155 | TASK [use-our-mirror : Retrieve the IP address]
2026-01-18 00:21:28.099719 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-01-18 00:21:28.110481 | 
2026-01-18 00:21:28.110597 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-01-18 00:21:28.656367 | controller | skipping: Conditional result was False
2026-01-18 00:21:28.663203 | 
2026-01-18 00:21:28.663298 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-01-18 00:21:28.688010 | controller | skipping: Conditional result was False
2026-01-18 00:21:28.694395 | 
2026-01-18 00:21:28.694465 | TASK [use-our-mirror : Create the podman configuration directory]
2026-01-18 00:21:28.718138 | controller | skipping: Conditional result was False
2026-01-18 00:21:28.724485 | 
2026-01-18 00:21:28.724586 | TASK [use-our-mirror : Copy the podman configuration]
2026-01-18 00:21:28.748808 | controller | skipping: Conditional result was False
2026-01-18 00:21:28.755235 | 
2026-01-18 00:21:28.755308 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-01-18 00:21:28.770228 | controller | skipping: Conditional result was False
2026-01-18 00:21:28.785377 | 
2026-01-18 00:21:28.785540 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-01-18 00:21:28.805967 | controller | skipping: Conditional result was False
2026-01-18 00:21:28.822713 | 
2026-01-18 00:21:28.822865 | TASK [Disable Fedora Modular]
2026-01-18 00:21:29.070143 | controller | changed
2026-01-18 00:21:29.076262 | 
2026-01-18 00:21:29.076330 | TASK [Enable EPEL]
2026-01-18 00:21:29.100763 | controller | skipping: Conditional result was False
2026-01-18 00:21:29.107893 | 
2026-01-18 00:21:29.107963 | TASK [Register the RHEL node]
2026-01-18 00:21:29.655512 | 
2026-01-18 00:21:29.655841 | TASK [Show the subscription-manager status]
2026-01-18 00:21:30.252800 | controller | skipping: Conditional result was False
2026-01-18 00:21:30.267797 | 
2026-01-18 00:21:30.267972 | TASK [Enable EPEL on RHEL]
2026-01-18 00:21:30.824633 | controller | skipping: Conditional result was False
2026-01-18 00:21:30.834076 | 
2026-01-18 00:21:30.834246 | TASK [Install git and tox]
2026-01-18 00:23:03.251978 | controller | changed
2026-01-18 00:23:03.261011 | 
2026-01-18 00:23:03.261129 | TASK [include_role : prepare-workspace]
2026-01-18 00:23:03.301962 | controller | ok
2026-01-18 00:23:03.330977 | 
2026-01-18 00:23:03.331104 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-18 00:23:03.585105 | controller | ok
2026-01-18 00:23:03.600557 | 
2026-01-18 00:23:03.600838 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-18 00:28:29.982573 | controller | Output suppressed because no_log was given
2026-01-18 00:28:29.992996 | 
2026-01-18 00:28:29.993080 | TASK [include_role : prepare-workspace-openshift]
2026-01-18 00:28:30.018109 | controller | skipping: Conditional result was False
2026-01-18 00:28:30.042366 | 
2026-01-18 00:28:30.042495 | PLAY [all:!appliance]
2026-01-18 00:28:30.064490 | 
2026-01-18 00:28:30.064603 | TASK [Run add-build-sshkey role (RSA)]
2026-01-18 00:28:30.085775 | controller | ok
2026-01-18 00:28:30.102690 | 
2026-01-18 00:28:30.102777 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-18 00:28:30.371991 | controller -> localhost | ok
2026-01-18 00:28:30.380004 | 
2026-01-18 00:28:30.380098 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-18 00:28:30.411454 | controller | ok
2026-01-18 00:28:30.427632 | controller | included: /var/lib/zuul/builds/fed7acce2a2f4bd7b09c348625becd21/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-18 00:28:30.434188 | 
2026-01-18 00:28:30.434256 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-18 00:28:31.019371 | controller -> localhost | Generating public/private rsa key pair.
2026-01-18 00:28:31.019737 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/fed7acce2a2f4bd7b09c348625becd21/work/fed7acce2a2f4bd7b09c348625becd21_id_rsa.
2026-01-18 00:28:31.019812 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/fed7acce2a2f4bd7b09c348625becd21/work/fed7acce2a2f4bd7b09c348625becd21_id_rsa.pub.
2026-01-18 00:28:31.019862 | controller -> localhost | The key fingerprint is:
2026-01-18 00:28:31.019908 | controller -> localhost | SHA256:QhNs+pN7u10KBduTmtXTvSQyr+yxGoTmp9EVrdikJKc zuul-build-sshkey
2026-01-18 00:28:31.019952 | controller -> localhost | The key's randomart image is:
2026-01-18 00:28:31.019994 | controller -> localhost | +---[RSA 2048]----+
2026-01-18 00:28:31.020035 | controller -> localhost | |     ..          |
2026-01-18 00:28:31.020093 | controller -> localhost | |      o.    .    |
2026-01-18 00:28:31.020154 | controller -> localhost | |     oo..o o .   |
2026-01-18 00:28:31.020207 | controller -> localhost | |    .. .*+=oo. . |
2026-01-18 00:28:31.020263 | controller -> localhost | |     ..ES+**o....|
2026-01-18 00:28:31.020330 | controller -> localhost | |      *.o=..+.o .|
2026-01-18 00:28:31.020388 | controller -> localhost | |       =++ ... . |
2026-01-18 00:28:31.020437 | controller -> localhost | |      . =ooo+    |
2026-01-18 00:28:31.020480 | controller -> localhost | |       oo+==     |
2026-01-18 00:28:31.020529 | controller -> localhost | +----[SHA256]-----+
2026-01-18 00:28:31.020629 | controller -> localhost | ok: Runtime: 0:00:00.148903
2026-01-18 00:28:31.035444 | 
2026-01-18 00:28:31.035601 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-18 00:28:31.075380 | controller | ok
2026-01-18 00:28:31.100216 | controller | included: /var/lib/zuul/builds/fed7acce2a2f4bd7b09c348625becd21/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-18 00:28:31.113416 | 
2026-01-18 00:28:31.113510 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-18 00:28:31.138311 | controller | skipping: Conditional result was False
2026-01-18 00:28:31.151387 | 
2026-01-18 00:28:31.151528 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-18 00:28:31.792537 | controller | changed
2026-01-18 00:28:31.806030 | 
2026-01-18 00:28:31.806168 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-18 00:28:32.047040 | controller | ok
2026-01-18 00:28:32.061615 | 
2026-01-18 00:28:32.061843 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-18 00:28:32.691421 | controller | changed
2026-01-18 00:28:32.697470 | 
2026-01-18 00:28:32.697548 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-18 00:28:33.300745 | controller | changed
2026-01-18 00:28:33.313347 | 
2026-01-18 00:28:33.313512 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-18 00:28:33.350598 | controller | skipping: Conditional result was False
2026-01-18 00:28:33.360867 | 
2026-01-18 00:28:33.361023 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-18 00:28:33.808090 | controller -> localhost | changed
2026-01-18 00:28:33.826760 | 
2026-01-18 00:28:33.826959 | TASK [add-build-sshkey : Add back temp key]
2026-01-18 00:28:34.131750 | controller -> localhost | Identity added: /var/lib/zuul/builds/fed7acce2a2f4bd7b09c348625becd21/work/fed7acce2a2f4bd7b09c348625becd21_id_rsa (zuul-build-sshkey)
2026-01-18 00:28:34.132127 | controller -> localhost | ok: Runtime: 0:00:00.010478
2026-01-18 00:28:34.145904 | 
2026-01-18 00:28:34.146014 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-18 00:28:34.512185 | controller | ok
2026-01-18 00:28:34.521463 | 
2026-01-18 00:28:34.521571 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-18 00:28:34.548644 | controller | skipping: Conditional result was False
2026-01-18 00:28:34.561357 | 
2026-01-18 00:28:34.561462 | TASK [Run add-build-sshkey role (ECDSA)]
2026-01-18 00:28:34.583828 | controller | ok
2026-01-18 00:28:34.603490 | 
2026-01-18 00:28:34.603629 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-18 00:28:34.878251 | controller -> localhost | ok
2026-01-18 00:28:34.888270 | 
2026-01-18 00:28:34.888420 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-18 00:28:34.914998 | controller | ok
2026-01-18 00:28:34.931735 | controller | included: /var/lib/zuul/builds/fed7acce2a2f4bd7b09c348625becd21/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-18 00:28:34.941873 | 
2026-01-18 00:28:34.942071 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-18 00:28:35.262332 | controller -> localhost | Generating public/private ecdsa key pair.
2026-01-18 00:28:35.262572 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/fed7acce2a2f4bd7b09c348625becd21/work/fed7acce2a2f4bd7b09c348625becd21_id_ecdsa.
2026-01-18 00:28:35.262607 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/fed7acce2a2f4bd7b09c348625becd21/work/fed7acce2a2f4bd7b09c348625becd21_id_ecdsa.pub.
2026-01-18 00:28:35.262655 | controller -> localhost | The key fingerprint is:
2026-01-18 00:28:35.262703 | controller -> localhost | SHA256:WcZJvbb6ulvjEHg1eXchVSAyGsNO/QLvynrH/nlWmSE zuul-build-sshkey
2026-01-18 00:28:35.262738 | controller -> localhost | The key's randomart image is:
2026-01-18 00:28:35.262765 | controller -> localhost | +---[ECDSA 521]---+
2026-01-18 00:28:35.262789 | controller -> localhost | |       .o.+...o+o|
2026-01-18 00:28:35.262812 | controller -> localhost | |        +*.+.o. .|
2026-01-18 00:28:35.262835 | controller -> localhost | |       o.o=.+.. o|
2026-01-18 00:28:35.262857 | controller -> localhost | |        .=o.+E o.|
2026-01-18 00:28:35.262879 | controller -> localhost | |        S.oo .. +|
2026-01-18 00:28:35.262901 | controller -> localhost | |         ....  o.|
2026-01-18 00:28:35.262923 | controller -> localhost | |       . o..o  . |
2026-01-18 00:28:35.262945 | controller -> localhost | |        + ++ oo  |
2026-01-18 00:28:35.262968 | controller -> localhost | |      .o o=*=o   |
2026-01-18 00:28:35.262992 | controller -> localhost | +----[SHA256]-----+
2026-01-18 00:28:35.263057 | controller -> localhost | ok: Runtime: 0:00:00.015800
2026-01-18 00:28:35.274383 | 
2026-01-18 00:28:35.274548 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-18 00:28:35.310251 | controller | ok
2026-01-18 00:28:35.323338 | controller | included: /var/lib/zuul/builds/fed7acce2a2f4bd7b09c348625becd21/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-18 00:28:35.338237 | 
2026-01-18 00:28:35.338377 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-18 00:28:35.364578 | controller | skipping: Conditional result was False
2026-01-18 00:28:35.375045 | 
2026-01-18 00:28:35.375212 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-18 00:28:35.659043 | controller | changed
2026-01-18 00:28:35.673487 | 
2026-01-18 00:28:35.673627 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-18 00:28:35.895064 | controller | ok
2026-01-18 00:28:35.901300 | 
2026-01-18 00:28:35.901378 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-18 00:28:36.498011 | controller | changed
2026-01-18 00:28:36.507491 | 
2026-01-18 00:28:36.507591 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-18 00:28:37.096107 | controller | changed
2026-01-18 00:28:37.101765 | 
2026-01-18 00:28:37.101840 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-18 00:28:37.127465 | controller | skipping: Conditional result was False
2026-01-18 00:28:37.134469 | 
2026-01-18 00:28:37.134545 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-18 00:28:37.414131 | controller -> localhost | changed
2026-01-18 00:28:37.430939 | 
2026-01-18 00:28:37.431059 | TASK [add-build-sshkey : Add back temp key]
2026-01-18 00:28:37.771914 | controller -> localhost | Identity added: /var/lib/zuul/builds/fed7acce2a2f4bd7b09c348625becd21/work/fed7acce2a2f4bd7b09c348625becd21_id_ecdsa (zuul-build-sshkey)
2026-01-18 00:28:37.772208 | controller -> localhost | ok: Runtime: 0:00:00.017858
2026-01-18 00:28:37.781912 | 
2026-01-18 00:28:37.782032 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-18 00:28:37.991525 | controller | ok
2026-01-18 00:28:37.999699 | 
2026-01-18 00:28:37.999825 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-18 00:28:38.026317 | controller | skipping: Conditional result was False
2026-01-18 00:28:38.042336 | 
2026-01-18 00:28:38.042462 | TASK [include_role : remove-zuul-sshkey]
2026-01-18 00:28:38.068825 | controller | skipping: Conditional result was False
2026-01-18 00:28:38.080535 | 
2026-01-18 00:28:38.080728 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-18 00:28:38.298105 | controller | ok: "logs"
2026-01-18 00:28:38.298362 | controller | ok: All items complete
2026-01-18 00:28:38.298391 | 
2026-01-18 00:28:38.482642 | controller | ok: "artifacts"
2026-01-18 00:28:38.667338 | controller | ok: "docs"
2026-01-18 00:28:38.686634 | 
2026-01-18 00:28:38.686804 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-18 00:28:38.934402 | controller | changed: "logs"
2026-01-18 00:28:39.132327 | controller | changed: "artifacts"
2026-01-18 00:28:39.334736 | controller | changed: "docs"
2026-01-18 00:28:39.384015 | 
2026-01-18 00:28:39.384154 | PLAY RECAP
2026-01-18 00:28:39.384237 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-01-18 00:28:39.384295 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-18 00:28:39.384333 | 
2026-01-18 00:28:39.522425 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-18 00:28:39.523542 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-18 00:28:40.207057 | 
2026-01-18 00:28:40.207268 | PLAY [all]
2026-01-18 00:28:40.231857 | 
2026-01-18 00:28:40.232016 | TASK [Install binary dependencies]
2026-01-18 00:28:40.311118 | controller | ok
2026-01-18 00:28:40.346226 | 
2026-01-18 00:28:40.346428 | TASK [bindep : Include find tasks]
2026-01-18 00:28:40.390309 | controller | ok
2026-01-18 00:28:40.401663 | controller | included: /var/lib/zuul/builds/fed7acce2a2f4bd7b09c348625becd21/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-01-18 00:28:40.410425 | 
2026-01-18 00:28:40.410514 | TASK [bindep : Look for bindep.txt]
2026-01-18 00:28:40.995596 | controller | ok
2026-01-18 00:28:41.009317 | 
2026-01-18 00:28:41.009476 | TASK [bindep : Define bindep_file fact]
2026-01-18 00:28:41.037785 | controller | skipping: Conditional result was False
2026-01-18 00:28:41.051633 | 
2026-01-18 00:28:41.051811 | TASK [bindep : Look for other-requirements.txt]
2026-01-18 00:28:41.264459 | controller | ok
2026-01-18 00:28:41.276790 | 
2026-01-18 00:28:41.276940 | TASK [bindep : Define bindep_file fact]
2026-01-18 00:28:41.314317 | controller | skipping: Conditional result was False
2026-01-18 00:28:41.328155 | 
2026-01-18 00:28:41.328354 | TASK [bindep : Look for bindep fallback file]
2026-01-18 00:28:41.365986 | controller | skipping: Conditional result was False
2026-01-18 00:28:41.380480 | 
2026-01-18 00:28:41.380766 | TASK [bindep : Define bindep_file fact]
2026-01-18 00:28:41.408758 | controller | skipping: Conditional result was False
2026-01-18 00:28:41.422793 | 
2026-01-18 00:28:41.422965 | TASK [bindep : Include bindep tasks]
2026-01-18 00:28:41.449919 | controller | skipping: Conditional result was False
2026-01-18 00:28:41.464157 | 
2026-01-18 00:28:41.464359 | TASK [bindep : Include install tasks]
2026-01-18 00:28:41.492407 | controller | skipping: Conditional result was False
2026-01-18 00:28:41.507297 | 
2026-01-18 00:28:41.507480 | LOOP [bindep : Include package tasks]
2026-01-18 00:28:41.585355 | 
2026-01-18 00:28:41.585898 | TASK [Run test-setup role]
2026-01-18 00:28:41.622565 | controller | ok
2026-01-18 00:28:41.654740 | 
2026-01-18 00:28:41.654887 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-01-18 00:28:41.909578 | controller | ok
2026-01-18 00:28:41.922971 | 
2026-01-18 00:28:41.923120 | TASK [test-setup : Run tools/test-setup.sh]
2026-01-18 00:28:42.475071 | controller | skipping: Conditional result was False
2026-01-18 00:28:42.531140 | 
2026-01-18 00:28:42.531310 | PLAY RECAP
2026-01-18 00:28:42.531369 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-18 00:28:42.531396 | 
2026-01-18 00:28:42.701621 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-18 00:28:42.703477 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-18 00:28:43.301128 | 
2026-01-18 00:28:43.301261 | PLAY [controller]
2026-01-18 00:28:43.322832 | 
2026-01-18 00:28:43.322927 | TASK [Create the /root directory]
2026-01-18 00:28:43.902524 | controller | ok
2026-01-18 00:28:43.908842 | 
2026-01-18 00:28:43.909014 | TASK [Install glibc-langpack-en]
2026-01-18 00:28:47.827311 | controller | ok: Nothing to do
2026-01-18 00:28:47.836194 | 
2026-01-18 00:28:47.836296 | TASK [Ensure controller directory exists]
2026-01-18 00:28:48.042124 | controller | changed
2026-01-18 00:28:48.049163 | 
2026-01-18 00:28:48.049252 | TASK [Install container runtime]
2026-01-18 00:28:48.111854 | controller | ok
2026-01-18 00:28:48.148388 | 
2026-01-18 00:28:48.148531 | LOOP [ensure-podman : Find distribution installation]
2026-01-18 00:28:48.187179 | controller | ok: "/var/lib/zuul/builds/fed7acce2a2f4bd7b09c348625becd21/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml"
2026-01-18 00:28:48.194422 | controller | included: /var/lib/zuul/builds/fed7acce2a2f4bd7b09c348625becd21/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml
2026-01-18 00:28:48.200883 | 
2026-01-18 00:28:48.200953 | TASK [ensure-podman : Install podman (RedHat)]
2026-01-18 00:29:53.539861 | controller | changed
2026-01-18 00:29:53.550413 | 
2026-01-18 00:29:53.550553 | TASK [ensure-podman : Fetch podman version]
2026-01-18 00:29:54.082875 | controller | Client:       Podman Engine
2026-01-18 00:29:54.107912 | controller | Version:      4.6.2
2026-01-18 00:29:54.107951 | controller | API Version:  4.6.2
2026-01-18 00:29:54.107959 | controller | Go Version:   go1.19.12
2026-01-18 00:29:54.107977 | controller | Built:        Mon Aug 28 19:38:31 2023
2026-01-18 00:29:54.107985 | controller | OS/Arch:      linux/amd64
2026-01-18 00:29:54.596210 | controller | ok: Runtime: 0:00:00.184787
2026-01-18 00:29:54.612416 | 
2026-01-18 00:29:54.612636 | TASK [ensure-podman : Print podman version installed]
2026-01-18 00:29:54.668813 | Podman version: Client:       Podman Engine
2026-01-18 00:29:54.669180 | Version:      4.6.2
2026-01-18 00:29:54.669278 | API Version:  4.6.2
2026-01-18 00:29:54.669354 | Go Version:   go1.19.12
2026-01-18 00:29:54.669459 | Built:        Mon Aug 28 19:38:31 2023
2026-01-18 00:29:54.669541 | OS/Arch:      linux/amd64
2026-01-18 00:29:54.681783 | 
2026-01-18 00:29:54.681954 | TASK [ensure-podman : Validate podman engine]
2026-01-18 00:29:55.222908 | controller | skipping: Conditional result was False
2026-01-18 00:29:55.232240 | 
2026-01-18 00:29:55.232375 | TASK [ensure-podman : Set up docker compatability socket]
2026-01-18 00:29:55.258627 | controller | skipping: Conditional result was False
2026-01-18 00:29:55.275449 | 
2026-01-18 00:29:55.275605 | TASK [Ensure python3.8 is present]
2026-01-18 00:29:55.291480 | controller | skipping: Conditional result was False
2026-01-18 00:29:55.299970 | 
2026-01-18 00:29:55.300141 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-01-18 00:29:55.321228 | controller | ok
2026-01-18 00:29:55.345406 | 
2026-01-18 00:29:55.345567 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-01-18 00:29:56.657001 | controller | ok: Nothing to do
2026-01-18 00:29:56.666636 | 
2026-01-18 00:29:56.666799 | TASK [our-ensure-python : Also install python3-devel]
2026-01-18 00:30:06.088280 | controller | changed
2026-01-18 00:30:06.107252 | 
2026-01-18 00:30:06.107455 | TASK [Run ensure-virtualenv role]
2026-01-18 00:30:06.131909 | controller | ok
2026-01-18 00:30:06.158125 | 
2026-01-18 00:30:06.158290 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-01-18 00:30:06.385681 | controller | /usr/bin/virtualenv
2026-01-18 00:30:06.694871 | controller | ok: Runtime: 0:00:00.004955
2026-01-18 00:30:06.701338 | 
2026-01-18 00:30:06.701446 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-01-18 00:30:06.730148 | controller | skipping: Conditional result was False
2026-01-18 00:30:06.730486 | controller | ok: All items complete
2026-01-18 00:30:06.730529 | 
2026-01-18 00:30:06.748264 | 
2026-01-18 00:30:06.748429 | TASK [Find the full path of the Python interpreter]
2026-01-18 00:30:07.010632 | controller | /usr/bin/python3
2026-01-18 00:30:07.343216 | controller | ok
2026-01-18 00:30:07.350176 | 
2026-01-18 00:30:07.350332 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-01-18 00:30:08.260570 | controller | created virtual environment CPython3.11.0.final.0-64 in 460ms
2026-01-18 00:30:08.276571 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-01-18 00:30:08.276595 | controller |   seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv)
2026-01-18 00:30:08.276605 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-01-18 00:30:08.276618 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-01-18 00:30:08.389302 | controller | changed
2026-01-18 00:30:08.406628 | 
2026-01-18 00:30:08.406887 | TASK [Set selinux package]
2026-01-18 00:30:08.452151 | controller | ok
2026-01-18 00:30:08.468657 | 
2026-01-18 00:30:08.468925 | TASK [Set selinux package (Fedora)]
2026-01-18 00:30:08.517641 | controller | ok
2026-01-18 00:30:08.526377 | 
2026-01-18 00:30:08.526499 | TASK [Install selinux into virtualenv]
2026-01-18 00:30:09.848446 | controller | Collecting selinux-please-lie-to-me
2026-01-18 00:30:09.888750 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-01-18 00:30:10.194934 | controller | Collecting setuptools<50.0.0
2026-01-18 00:30:10.202075 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-01-18 00:30:10.241208 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 22.3 MB/s eta 0:00:00
2026-01-18 00:30:10.319726 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-01-18 00:30:10.319974 | controller |   Attempting uninstall: setuptools
2026-01-18 00:30:10.322345 | controller |     Found existing installation: setuptools 62.6.0
2026-01-18 00:30:10.381822 | controller |     Uninstalling setuptools-62.6.0:
2026-01-18 00:30:10.389658 | controller |       Successfully uninstalled setuptools-62.6.0
2026-01-18 00:30:10.787202 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-01-18 00:30:10.950638 | controller |
2026-01-18 00:30:11.028204 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3
2026-01-18 00:30:11.028248 | controller | [notice] To update, run: python -m pip install --upgrade pip
2026-01-18 00:30:11.078954 | controller | ok: Runtime: 0:00:02.191285
2026-01-18 00:30:11.093670 | 
2026-01-18 00:30:11.093907 | TASK [Install pytest-forked into virtualenv]
2026-01-18 00:30:11.687850 | controller | Collecting pytest-forked
2026-01-18 00:30:11.723846 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-01-18 00:30:11.764608 | controller | Collecting py
2026-01-18 00:30:11.768846 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-01-18 00:30:11.788286 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 5.5 MB/s eta 0:00:00
2026-01-18 00:30:11.891151 | controller | Collecting pytest>=3.10
2026-01-18 00:30:11.895675 | controller |   Downloading pytest-9.0.2-py3-none-any.whl (374 kB)
2026-01-18 00:30:11.910905 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 374.8/374.8 kB 29.4 MB/s eta 0:00:00
2026-01-18 00:30:11.947440 | controller | Collecting iniconfig>=1.0.1
2026-01-18 00:30:11.951203 | controller |   Downloading iniconfig-2.3.0-py3-none-any.whl (7.5 kB)
2026-01-18 00:30:11.994572 | controller | Collecting packaging>=22
2026-01-18 00:30:11.997792 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2026-01-18 00:30:12.009806 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 5.9 MB/s eta 0:00:00
2026-01-18 00:30:12.042166 | controller | Collecting pluggy<2,>=1.5
2026-01-18 00:30:12.045481 | controller |   Downloading pluggy-1.6.0-py3-none-any.whl (20 kB)
2026-01-18 00:30:12.088520 | controller | Collecting pygments>=2.7.2
2026-01-18 00:30:12.092076 | controller |   Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB)
2026-01-18 00:30:12.118552 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 54.0 MB/s eta 0:00:00
2026-01-18 00:30:12.188819 | controller | Installing collected packages: pygments, py, pluggy, packaging, iniconfig, pytest, pytest-forked
2026-01-18 00:30:13.284491 | controller | Successfully installed iniconfig-2.3.0 packaging-25.0 pluggy-1.6.0 py-1.11.0 pygments-2.19.2 pytest-9.0.2 pytest-forked-1.6.0
2026-01-18 00:30:13.293173 | controller |
2026-01-18 00:30:13.362179 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3
2026-01-18 00:30:13.362222 | controller | [notice] To update, run: python -m pip install --upgrade pip
2026-01-18 00:30:13.691087 | controller | ok: Runtime: 0:00:02.039419
2026-01-18 00:30:13.710153 | 
2026-01-18 00:30:13.710488 | TASK [Update pip]
2026-01-18 00:30:14.243993 | controller | Requirement already satisfied: pip in ./venv/lib/python3.11/site-packages (22.2.2)
2026-01-18 00:30:14.375939 | controller | Collecting pip
2026-01-18 00:30:14.409052 | controller |   Downloading pip-25.3-py3-none-any.whl (1.8 MB)
2026-01-18 00:30:14.474412 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 29.4 MB/s eta 0:00:00
2026-01-18 00:30:14.537438 | controller | Installing collected packages: pip
2026-01-18 00:30:14.537677 | controller |   Attempting uninstall: pip
2026-01-18 00:30:14.539827 | controller |     Found existing installation: pip 22.2.2
2026-01-18 00:30:14.677027 | controller |     Uninstalling pip-22.2.2:
2026-01-18 00:30:14.692068 | controller |       Successfully uninstalled pip-22.2.2
2026-01-18 00:30:15.555302 | controller | Successfully installed pip-25.3
2026-01-18 00:30:15.786823 | controller | ok: Runtime: 0:00:01.679567
2026-01-18 00:30:15.793205 | 
2026-01-18 00:30:15.793286 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-01-18 00:30:16.011743 | controller | changed
2026-01-18 00:30:16.017991 | 
2026-01-18 00:30:16.018062 | TASK [Install ansible into virtualenv]
2026-01-18 00:30:16.500010 | controller | Processing ./src/github.com/ansible/ansible
2026-01-18 00:30:16.503444 | controller |   Installing build dependencies: started
2026-01-18 00:30:17.471212 | controller |   Installing build dependencies: finished with status 'done'
2026-01-18 00:34:55.179794 | [Zuul] Log Stream did not terminate
2026-01-18 00:34:55.180180 | controller | ERROR
2026-01-18 00:34:55.180288 | controller | {
2026-01-18 00:34:55.180316 | controller |   "msg": "Data could not be sent to remote host \"162.253.55.218\". Make sure this host can be reached over ssh: ssh: connect to host 162.253.55.218 port 22: No route to host\r\n",
2026-01-18 00:34:55.180346 | controller |   "unreachable": true
2026-01-18 00:34:55.180390 | controller | }
unreachable
2026-01-18 00:34:55.182513 | 
2026-01-18 00:34:55.182572 | PLAY RECAP
2026-01-18 00:34:55.182638 | controller | ok: 18 changed: 10 unreachable: 1 failed: 0 skipped: 4 rescued: 0 ignored: 0
2026-01-18 00:34:55.182664 | 
2026-01-18 00:34:55.396613 | PRE-RUN END RESULT_UNREACHABLE: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-18 00:34:55.398779 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-18 00:34:56.020836 | 
2026-01-18 00:34:56.020980 | PLAY [all]
2026-01-18 00:34:56.043442 | 
2026-01-18 00:34:56.043571 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-01-18 00:35:07.719256 | controller | ERROR
2026-01-18 00:35:07.719724 | controller | {
2026-01-18 00:35:07.719821 | controller |   "msg": "Failed to connect to the host via ssh: ssh: connect to host 162.253.55.218 port 22: No route to host",
2026-01-18 00:35:07.719891 | controller |   "unreachable": true
2026-01-18 00:35:07.719992 | controller | }
unreachable
2026-01-18 00:35:07.721828 | 
2026-01-18 00:35:07.721928 | PLAY RECAP
2026-01-18 00:35:07.722017 | controller | ok: 0 changed: 0 unreachable: 1 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-18 00:35:07.722066 | 
2026-01-18 00:35:07.847661 | POST-RUN END RESULT_UNREACHABLE: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-18 00:35:07.848989 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-01-18 00:35:08.465400 | 
2026-01-18 00:35:08.465635 | PLAY [all:!appliance*]
2026-01-18 00:35:08.490643 | 
2026-01-18 00:35:08.490770 | TASK [unregister the node]
2026-01-18 00:35:10.762078 | [controller] Waiting on logger
2026-01-18 00:35:18.662484 | controller | skipping: Conditional result was False
2026-01-18 00:35:18.671704 | 
2026-01-18 00:35:18.671829 | TASK [include_role : fetch-output]
2026-01-18 00:35:18.725330 | controller | ok
2026-01-18 00:35:18.747779 | 
2026-01-18 00:35:18.747922 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-18 00:35:18.823787 | controller | skipping: Conditional result was False
2026-01-18 00:35:18.839157 | 
2026-01-18 00:35:18.839332 | TASK [fetch-output : Set log path for single node]
2026-01-18 00:35:18.888666 | controller | ok
2026-01-18 00:35:18.898453 | 
2026-01-18 00:35:18.898606 | LOOP [fetch-output : Ensure local output dirs]
2026-01-18 00:35:19.390807 | controller -> localhost | ok: "/var/lib/zuul/builds/fed7acce2a2f4bd7b09c348625becd21/work/logs"
2026-01-18 00:35:19.676017 | controller -> localhost | changed: "/var/lib/zuul/builds/fed7acce2a2f4bd7b09c348625becd21/work/artifacts"
2026-01-18 00:35:19.950080 | controller -> localhost | changed: "/var/lib/zuul/builds/fed7acce2a2f4bd7b09c348625becd21/work/docs"
2026-01-18 00:35:19.966843 | 
2026-01-18 00:35:19.967036 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-18 00:35:20.683424 | controller | changed:
2026-01-18 00:35:20.683706 | controller | .d..t...... ./
2026-01-18 00:35:20.683745 | controller | cd+++++++++ controller/
2026-01-18 00:35:20.683785 | controller | changed: All items complete
2026-01-18 00:35:20.683808 | 
2026-01-18 00:35:21.218973 | controller | changed: .d..t...... ./
2026-01-18 00:35:21.682263 | controller | changed: .d..t...... ./
2026-01-18 00:35:21.717763 | 
2026-01-18 00:35:21.717992 | TASK [include_role : fetch-output-openshift]
2026-01-18 00:35:21.746447 | controller | skipping: Conditional result was False
2026-01-18 00:35:21.762246 | 
2026-01-18 00:35:21.762393 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-18 00:35:21.818301 | controller | skipping: Conditional result was False
2026-01-18 00:35:21.830618 | controller | skipping: Conditional result was False
2026-01-18 00:35:21.874195 | 
2026-01-18 00:35:21.874357 | PLAY [localhost]
2026-01-18 00:35:21.887199 | 
2026-01-18 00:35:21.887278 | TASK [Run Zuul manifest role]
2026-01-18 00:35:21.908800 | localhost | ok
2026-01-18 00:35:21.925373 | 
2026-01-18 00:35:21.925513 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-18 00:35:22.402829 | localhost | changed
2026-01-18 00:35:22.414911 | 
2026-01-18 00:35:22.415042 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-18 00:35:22.447131 | localhost | ok
2026-01-18 00:35:22.455989 | 
2026-01-18 00:35:22.456066 | TASK [Set zuul-log-path fact]
2026-01-18 00:35:22.474830 | localhost | ok
2026-01-18 00:35:22.490394 | 
2026-01-18 00:35:22.490536 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-18 00:35:22.523537 | localhost | ok
2026-01-18 00:35:22.534519 | 
2026-01-18 00:35:22.534705 | LOOP [Run upload-logs-swift role]
2026-01-18 00:35:22.573324 | localhost | Output suppressed because no_log was given
2026-01-18 00:35:22.613848 | 
2026-01-18 00:35:22.614156 | TASK [Set zuul-log-path fact]
2026-01-18 00:35:22.639377 | localhost | skipping: Conditional result was False
2026-01-18 00:35:22.645595 | 
2026-01-18 00:35:22.645752 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-01-18 00:35:23.173036 | localhost -> localhost | ok: Runtime: 0:00:00.012657
2026-01-18 00:35:23.224911 | 
2026-01-18 00:35:23.225075 | TASK [upload-logs-swift : Upload logs to swift]