2026-02-20 00:22:08.800603 | Job console starting...
2026-02-20 00:22:08.817083 | Updating repositories
2026-02-20 00:22:08.969854 | Preparing job workspace
2026-02-20 00:22:12.928794 | Running Ansible setup...
2026-02-20 00:22:17.396983 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-02-20 00:22:18.004626 | 
2026-02-20 00:22:18.004757 | PLAY [localhost]
2026-02-20 00:22:18.014057 | 
2026-02-20 00:22:18.014169 | TASK [Gathering Facts]
2026-02-20 00:22:18.947938 | localhost | ok
2026-02-20 00:22:18.964676 | 
2026-02-20 00:22:18.964812 | TASK [Setup log path fact]
2026-02-20 00:22:18.982658 | localhost | ok
2026-02-20 00:22:18.998514 | 
2026-02-20 00:22:18.998642 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-20 00:22:19.027545 | localhost | ok
2026-02-20 00:22:19.036808 | 
2026-02-20 00:22:19.036909 | TASK [emit-job-header : Print job information]
2026-02-20 00:22:19.068321 | # Job Information
2026-02-20 00:22:19.068501 | Ansible Version: 2.15.12
2026-02-20 00:22:19.068537 | Job: ansible-test-sanity-docker-milestone
2026-02-20 00:22:19.068561 | Pipeline: periodic
2026-02-20 00:22:19.068584 | Executor: ze01.softwarefactory-project.io
2026-02-20 00:22:19.068605 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-02-20 00:22:19.068629 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/d1e/ansible/d1e88d9b857b40f59b52bd41fc2555e1/
2026-02-20 00:22:19.068651 | Event ID: 6d539d67fcee4d5b96e508018f097453
2026-02-20 00:22:19.073595 | 
2026-02-20 00:22:19.073674 | LOOP [emit-job-header : Print node information]
2026-02-20 00:22:19.186379 | localhost | ok:
2026-02-20 00:22:19.186664 | localhost | # Node Information
2026-02-20 00:22:19.186708 | localhost | Inventory Hostname: controller
2026-02-20 00:22:19.186742 | localhost | Hostname: np0005625025
2026-02-20 00:22:19.186780 | localhost | Username: zuul
2026-02-20 00:22:19.186820 | localhost | Distro: Fedora 37
2026-02-20 00:22:19.186855 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2026-02-20 00:22:19.186887 | localhost | Region: ca-ymq-1
2026-02-20 00:22:19.186918 | localhost | Label: ansible-fedora-37-1vcpu
2026-02-20 00:22:19.186948 | localhost | Product Name: OpenStack Nova
2026-02-20 00:22:19.186977 | localhost | Interface IP: 199.204.45.205
2026-02-20 00:22:19.199047 | 
2026-02-20 00:22:19.199170 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-20 00:22:19.571937 | localhost -> localhost | changed
2026-02-20 00:22:19.580730 | 
2026-02-20 00:22:19.580857 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-20 00:22:20.473580 | localhost -> localhost | changed
2026-02-20 00:22:20.506310 | 
2026-02-20 00:22:20.506466 | PLAY [all:!appliance*]
2026-02-20 00:22:20.523959 | 
2026-02-20 00:22:20.524074 | TASK [include_role : start-zuul-console]
2026-02-20 00:22:20.543870 | controller | ok
2026-02-20 00:22:20.560327 | 
2026-02-20 00:22:20.560523 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-02-20 00:22:20.951385 | controller | ok
2026-02-20 00:22:20.968165 | 
2026-02-20 00:22:20.968276 | TASK [use-our-mirror : Retrieve the IP address]
2026-02-20 00:22:21.893864 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-02-20 00:22:21.906440 | 
2026-02-20 00:22:21.906604 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-02-20 00:22:22.460315 | controller | skipping: Conditional result was False
2026-02-20 00:22:22.476500 | 
2026-02-20 00:22:22.476687 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-02-20 00:22:22.504597 | controller | skipping: Conditional result was False
2026-02-20 00:22:22.519806 | 
2026-02-20 00:22:22.519988 | TASK [use-our-mirror : Create the podman configuration directory]
2026-02-20 00:22:22.546493 | controller | skipping: Conditional result was False
2026-02-20 00:22:22.563152 | 
2026-02-20 00:22:22.563341 | TASK [use-our-mirror : Copy the podman configuration]
2026-02-20 00:22:22.579890 | controller | skipping: Conditional result was False
2026-02-20 00:22:22.595203 | 
2026-02-20 00:22:22.595368 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-02-20 00:22:22.622359 | controller | skipping: Conditional result was False
2026-02-20 00:22:22.631095 | 
2026-02-20 00:22:22.631191 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-02-20 00:22:22.646830 | controller | skipping: Conditional result was False
2026-02-20 00:22:22.664680 | 
2026-02-20 00:22:22.664816 | TASK [Disable Fedora Modular]
2026-02-20 00:22:22.911050 | controller | changed
2026-02-20 00:22:22.918796 | 
2026-02-20 00:22:22.918871 | TASK [Enable EPEL]
2026-02-20 00:22:22.934178 | controller | skipping: Conditional result was False
2026-02-20 00:22:22.941697 | 
2026-02-20 00:22:22.941769 | TASK [Register the RHEL node]
2026-02-20 00:22:23.490140 | 
2026-02-20 00:22:23.490331 | TASK [Show the subscription-manager status]
2026-02-20 00:22:24.084703 | controller | skipping: Conditional result was False
2026-02-20 00:22:24.095162 | 
2026-02-20 00:22:24.095285 | TASK [Enable EPEL on RHEL]
2026-02-20 00:22:24.636225 | controller | skipping: Conditional result was False
2026-02-20 00:22:24.649826 | 
2026-02-20 00:22:24.650055 | TASK [Install git and tox]
2026-02-20 00:23:40.836852 | controller | changed
2026-02-20 00:23:40.843911 | 
2026-02-20 00:23:40.843998 | TASK [include_role : prepare-workspace]
2026-02-20 00:23:40.874316 | controller | ok
2026-02-20 00:23:40.896350 | 
2026-02-20 00:23:40.896468 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-20 00:23:41.139674 | controller | ok
2026-02-20 00:23:41.145905 | 
2026-02-20 00:23:41.145971 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-20 00:23:54.121975 | controller | Output suppressed because no_log was given
2026-02-20 00:23:54.160783 | 
2026-02-20 00:23:54.160881 | TASK [include_role : prepare-workspace-openshift]
2026-02-20 00:23:54.175284 | controller | skipping: Conditional result was False
2026-02-20 00:23:54.196537 | 
2026-02-20 00:23:54.196636 | PLAY [all:!appliance]
2026-02-20 00:23:54.213139 | 
2026-02-20 00:23:54.213241 | TASK [Run add-build-sshkey role (RSA)]
2026-02-20 00:23:54.231839 | controller | ok
2026-02-20 00:23:54.246436 | 
2026-02-20 00:23:54.246538 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-20 00:23:54.475073 | controller -> localhost | ok
2026-02-20 00:23:54.481857 | 
2026-02-20 00:23:54.481952 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-20 00:23:54.515308 | controller | ok
2026-02-20 00:23:54.530041 | controller | included: /var/lib/zuul/builds/d1e88d9b857b40f59b52bd41fc2555e1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-20 00:23:54.536984 | 
2026-02-20 00:23:54.537153 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-20 00:23:55.074616 | controller -> localhost | Generating public/private rsa key pair.
2026-02-20 00:23:55.074819 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/d1e88d9b857b40f59b52bd41fc2555e1/work/d1e88d9b857b40f59b52bd41fc2555e1_id_rsa.
2026-02-20 00:23:55.074848 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/d1e88d9b857b40f59b52bd41fc2555e1/work/d1e88d9b857b40f59b52bd41fc2555e1_id_rsa.pub.
2026-02-20 00:23:55.074869 | controller -> localhost | The key fingerprint is:
2026-02-20 00:23:55.074887 | controller -> localhost | SHA256:R9NY4WLDInSB0WzKceHdoHCKFK/udcQ1atR0o5RCsfU zuul-build-sshkey
2026-02-20 00:23:55.074905 | controller -> localhost | The key's randomart image is:
2026-02-20 00:23:55.074923 | controller -> localhost | +---[RSA 2048]----+
2026-02-20 00:23:55.074940 | controller -> localhost | |    o.+*B+=.=.   |
2026-02-20 00:23:55.074958 | controller -> localhost | |   . +o**X.@ .   |
2026-02-20 00:23:55.074976 | controller -> localhost | |    ..+=*.# E    |
2026-02-20 00:23:55.074993 | controller -> localhost | |     .o+ * =     |
2026-02-20 00:23:55.075010 | controller -> localhost | |    .   S .      |
2026-02-20 00:23:55.075069 | controller -> localhost | |   .   o .       |
2026-02-20 00:23:55.075095 | controller -> localhost | |    . . .        |
2026-02-20 00:23:55.075117 | controller -> localhost | |   . . .         |
2026-02-20 00:23:55.075135 | controller -> localhost | |    .            |
2026-02-20 00:23:55.075155 | controller -> localhost | +----[SHA256]-----+
2026-02-20 00:23:55.075205 | controller -> localhost | ok: Runtime: 0:00:00.094710
2026-02-20 00:23:55.082611 | 
2026-02-20 00:23:55.082717 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-20 00:23:55.112348 | controller | ok
2026-02-20 00:23:55.124216 | controller | included: /var/lib/zuul/builds/d1e88d9b857b40f59b52bd41fc2555e1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-20 00:23:55.135206 | 
2026-02-20 00:23:55.135339 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-20 00:23:55.149357 | controller | skipping: Conditional result was False
2026-02-20 00:23:55.157353 | 
2026-02-20 00:23:55.157493 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-20 00:23:55.603004 | controller | changed
2026-02-20 00:23:55.610323 | 
2026-02-20 00:23:55.610421 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-20 00:23:55.827316 | controller | ok
2026-02-20 00:23:55.837301 | 
2026-02-20 00:23:55.837411 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-20 00:23:56.509459 | controller | changed
2026-02-20 00:23:56.520923 | 
2026-02-20 00:23:56.521080 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-20 00:23:57.114571 | controller | changed
2026-02-20 00:23:57.125248 | 
2026-02-20 00:23:57.125331 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-20 00:23:57.151331 | controller | skipping: Conditional result was False
2026-02-20 00:23:57.160558 | 
2026-02-20 00:23:57.160724 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-20 00:23:57.637195 | controller -> localhost | changed
2026-02-20 00:23:57.650379 | 
2026-02-20 00:23:57.650482 | TASK [add-build-sshkey : Add back temp key]
2026-02-20 00:23:57.967882 | controller -> localhost | Identity added: /var/lib/zuul/builds/d1e88d9b857b40f59b52bd41fc2555e1/work/d1e88d9b857b40f59b52bd41fc2555e1_id_rsa (zuul-build-sshkey)
2026-02-20 00:23:57.968144 | controller -> localhost | ok: Runtime: 0:00:00.020044
2026-02-20 00:23:57.977988 | 
2026-02-20 00:23:57.978153 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-20 00:23:58.327432 | controller | ok
2026-02-20 00:23:58.334197 | 
2026-02-20 00:23:58.334332 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-20 00:23:58.359173 | controller | skipping: Conditional result was False
2026-02-20 00:23:58.374894 | 
2026-02-20 00:23:58.375047 | TASK [Run add-build-sshkey role (ECDSA)]
2026-02-20 00:23:58.406121 | controller | ok
2026-02-20 00:23:58.426453 | 
2026-02-20 00:23:58.426583 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-20 00:23:58.726648 | controller -> localhost | ok
2026-02-20 00:23:58.733734 | 
2026-02-20 00:23:58.733847 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-20 00:23:58.764866 | controller | ok
2026-02-20 00:23:58.777450 | controller | included: /var/lib/zuul/builds/d1e88d9b857b40f59b52bd41fc2555e1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-20 00:23:58.785167 | 
2026-02-20 00:23:58.785282 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-20 00:23:59.054501 | controller -> localhost | Generating public/private ecdsa key pair.
2026-02-20 00:23:59.054732 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/d1e88d9b857b40f59b52bd41fc2555e1/work/d1e88d9b857b40f59b52bd41fc2555e1_id_ecdsa.
2026-02-20 00:23:59.054776 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/d1e88d9b857b40f59b52bd41fc2555e1/work/d1e88d9b857b40f59b52bd41fc2555e1_id_ecdsa.pub.
2026-02-20 00:23:59.054810 | controller -> localhost | The key fingerprint is:
2026-02-20 00:23:59.054832 | controller -> localhost | SHA256:YKGrh1zvRaXZgCHSOeBkr1q68IB0cTUJ9JGo3B1EoVs zuul-build-sshkey
2026-02-20 00:23:59.054853 | controller -> localhost | The key's randomart image is:
2026-02-20 00:23:59.054873 | controller -> localhost | +---[ECDSA 521]---+
2026-02-20 00:23:59.054892 | controller -> localhost | |  =oo+BB+        |
2026-02-20 00:23:59.054911 | controller -> localhost | | + o+++Bo        |
2026-02-20 00:23:59.054929 | controller -> localhost | |  o.=+=Eo .      |
2026-02-20 00:23:59.054948 | controller -> localhost | |   +o+oo *       |
2026-02-20 00:23:59.054966 | controller -> localhost | | .o.o.  S .      |
2026-02-20 00:23:59.054985 | controller -> localhost | |o=.+ . .         |
2026-02-20 00:23:59.055003 | controller -> localhost | |* + . . .        |
2026-02-20 00:23:59.055159 | controller -> localhost | |.+ . . .         |
2026-02-20 00:23:59.055187 | controller -> localhost | |. .   .          |
2026-02-20 00:23:59.055207 | controller -> localhost | +----[SHA256]-----+
2026-02-20 00:23:59.055256 | controller -> localhost | ok: Runtime: 0:00:00.007496
2026-02-20 00:23:59.062087 | 
2026-02-20 00:23:59.062160 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-20 00:23:59.082749 | controller | ok
2026-02-20 00:23:59.090515 | controller | included: /var/lib/zuul/builds/d1e88d9b857b40f59b52bd41fc2555e1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-20 00:23:59.101701 | 
2026-02-20 00:23:59.101791 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-20 00:23:59.115614 | controller | skipping: Conditional result was False
2026-02-20 00:23:59.122318 | 
2026-02-20 00:23:59.122417 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-20 00:23:59.384631 | controller | changed
2026-02-20 00:23:59.399660 | 
2026-02-20 00:23:59.399970 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-20 00:23:59.631175 | controller | ok
2026-02-20 00:23:59.642558 | 
2026-02-20 00:23:59.642694 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-20 00:24:00.240907 | controller | changed
2026-02-20 00:24:00.247400 | 
2026-02-20 00:24:00.247480 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-20 00:24:00.872292 | controller | changed
2026-02-20 00:24:00.884242 | 
2026-02-20 00:24:00.884382 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-20 00:24:00.910290 | controller | skipping: Conditional result was False
2026-02-20 00:24:00.956191 | 
2026-02-20 00:24:00.956311 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-20 00:24:01.184935 | controller -> localhost | changed
2026-02-20 00:24:01.198155 | 
2026-02-20 00:24:01.198266 | TASK [add-build-sshkey : Add back temp key]
2026-02-20 00:24:01.524659 | controller -> localhost | Identity added: /var/lib/zuul/builds/d1e88d9b857b40f59b52bd41fc2555e1/work/d1e88d9b857b40f59b52bd41fc2555e1_id_ecdsa (zuul-build-sshkey)
2026-02-20 00:24:01.524913 | controller -> localhost | ok: Runtime: 0:00:00.006724
2026-02-20 00:24:01.541245 | 
2026-02-20 00:24:01.541391 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-20 00:24:01.746008 | controller | ok
2026-02-20 00:24:01.752944 | 
2026-02-20 00:24:01.753086 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-20 00:24:01.778829 | controller | skipping: Conditional result was False
2026-02-20 00:24:01.792969 | 
2026-02-20 00:24:01.793132 | TASK [include_role : remove-zuul-sshkey]
2026-02-20 00:24:01.818944 | controller | skipping: Conditional result was False
2026-02-20 00:24:01.828398 | 
2026-02-20 00:24:01.828523 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-20 00:24:02.044395 | controller | ok: "logs"
2026-02-20 00:24:02.044674 | controller | ok: All items complete
2026-02-20 00:24:02.044713 | 
2026-02-20 00:24:02.223795 | controller | ok: "artifacts"
2026-02-20 00:24:02.419082 | controller | ok: "docs"
2026-02-20 00:24:02.428899 | 
2026-02-20 00:24:02.429004 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-20 00:24:02.652174 | controller | changed: "logs"
2026-02-20 00:24:02.837883 | controller | changed: "artifacts"
2026-02-20 00:24:03.026267 | controller | changed: "docs"
2026-02-20 00:24:03.080650 | 
2026-02-20 00:24:03.080811 | PLAY RECAP
2026-02-20 00:24:03.080876 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-02-20 00:24:03.080915 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-20 00:24:03.080944 | 
2026-02-20 00:24:03.209608 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-02-20 00:24:03.210463 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-02-20 00:24:03.788084 | 
2026-02-20 00:24:03.788263 | PLAY [all]
2026-02-20 00:24:03.818147 | 
2026-02-20 00:24:03.818282 | TASK [Install binary dependencies]
2026-02-20 00:24:03.869105 | controller | ok
2026-02-20 00:24:03.890578 | 
2026-02-20 00:24:03.890722 | TASK [bindep : Include find tasks]
2026-02-20 00:24:03.920731 | controller | ok
2026-02-20 00:24:03.928738 | controller | included: /var/lib/zuul/builds/d1e88d9b857b40f59b52bd41fc2555e1/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-02-20 00:24:03.935665 | 
2026-02-20 00:24:03.935731 | TASK [bindep : Look for bindep.txt]
2026-02-20 00:24:04.318770 | controller | ok
2026-02-20 00:24:04.336187 | 
2026-02-20 00:24:04.336344 | TASK [bindep : Define bindep_file fact]
2026-02-20 00:24:04.361758 | controller | skipping: Conditional result was False
2026-02-20 00:24:04.371866 | 
2026-02-20 00:24:04.372237 | TASK [bindep : Look for other-requirements.txt]
2026-02-20 00:24:04.586298 | controller | ok
2026-02-20 00:24:04.601201 | 
2026-02-20 00:24:04.601478 | TASK [bindep : Define bindep_file fact]
2026-02-20 00:24:04.639217 | controller | skipping: Conditional result was False
2026-02-20 00:24:04.649561 | 
2026-02-20 00:24:04.649672 | TASK [bindep : Look for bindep fallback file]
2026-02-20 00:24:04.685133 | controller | skipping: Conditional result was False
2026-02-20 00:24:04.700179 | 
2026-02-20 00:24:04.700328 | TASK [bindep : Define bindep_file fact]
2026-02-20 00:24:04.724772 | controller | skipping: Conditional result was False
2026-02-20 00:24:04.730927 | 
2026-02-20 00:24:04.731042 | TASK [bindep : Include bindep tasks]
2026-02-20 00:24:04.754685 | controller | skipping: Conditional result was False
2026-02-20 00:24:04.760929 | 
2026-02-20 00:24:04.761008 | TASK [bindep : Include install tasks]
2026-02-20 00:24:04.784515 | controller | skipping: Conditional result was False
2026-02-20 00:24:04.790632 | 
2026-02-20 00:24:04.790711 | LOOP [bindep : Include package tasks]
2026-02-20 00:24:04.846268 | 
2026-02-20 00:24:04.846437 | TASK [Run test-setup role]
2026-02-20 00:24:04.866755 | controller | ok
2026-02-20 00:24:04.884207 | 
2026-02-20 00:24:04.884318 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-02-20 00:24:05.087073 | controller | ok
2026-02-20 00:24:05.093052 | 
2026-02-20 00:24:05.093115 | TASK [test-setup : Run tools/test-setup.sh]
2026-02-20 00:24:05.628091 | controller | skipping: Conditional result was False
2026-02-20 00:24:05.666852 | 
2026-02-20 00:24:05.666964 | PLAY RECAP
2026-02-20 00:24:05.667046 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-02-20 00:24:05.667083 | 
2026-02-20 00:24:05.784648 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-02-20 00:24:05.785611 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-02-20 00:24:06.400372 | 
2026-02-20 00:24:06.400557 | PLAY [controller]
2026-02-20 00:24:06.433556 | 
2026-02-20 00:24:06.433673 | TASK [Create the /root directory]
2026-02-20 00:24:06.812663 | controller | ok
2026-02-20 00:24:06.819871 | 
2026-02-20 00:24:06.819973 | TASK [Install glibc-langpack-en]
2026-02-20 00:24:10.701713 | controller | ok: Nothing to do
2026-02-20 00:24:10.707721 | 
2026-02-20 00:24:10.707798 | TASK [Ensure controller directory exists]
2026-02-20 00:24:10.914534 | controller | changed
2026-02-20 00:24:10.923779 | 
2026-02-20 00:24:10.924057 | TASK [Install container runtime]
2026-02-20 00:24:10.979559 | controller | ok
2026-02-20 00:24:11.031738 | 
2026-02-20 00:24:11.031859 | LOOP [ensure-podman : Find distribution installation]
2026-02-20 00:24:11.072377 | controller | ok: "/var/lib/zuul/builds/d1e88d9b857b40f59b52bd41fc2555e1/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml"
2026-02-20 00:24:11.081141 | controller | included: /var/lib/zuul/builds/d1e88d9b857b40f59b52bd41fc2555e1/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml
2026-02-20 00:24:11.089297 | 
2026-02-20 00:24:11.089403 | TASK [ensure-podman : Install podman (RedHat)]
2026-02-20 00:26:23.267909 | controller | ERROR
2026-02-20 00:26:23.268219 | controller | {
2026-02-20 00:26:23.268417 | controller |   "msg": "Timeout (32s) waiting for privilege escalation prompt: "
2026-02-20 00:26:23.268796 | controller | }
failure
2026-02-20 00:26:23.273161 | 
2026-02-20 00:26:23.273267 | PLAY RECAP
2026-02-20 00:26:23.273349 | controller | ok: 4 changed: 1 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-20 00:26:23.273395 | 
2026-02-20 00:26:23.379090 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-02-20 00:26:23.380082 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-02-20 00:26:23.997288 | 
2026-02-20 00:26:23.997446 | PLAY [all]
2026-02-20 00:26:24.023035 | 
2026-02-20 00:26:24.023183 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-02-20 00:30:24.823425 | controller | changed: non-zero return code
2026-02-20 00:30:24.839414 | 
2026-02-20 00:30:24.839615 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-02-20 00:30:24.873975 | controller | skipping: Conditional result was False
2026-02-20 00:30:24.885601 | 
2026-02-20 00:30:24.885733 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-02-20 00:30:24.923239 | 
2026-02-20 00:30:24.923546 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-02-20 00:30:24.963168 | 
2026-02-20 00:30:24.963470 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-02-20 00:30:24.991452 | controller | skipping: Conditional result was False
2026-02-20 00:30:25.001950 | 
2026-02-20 00:30:25.002125 | LOOP [fetch-subunit-output : Generate subunit file]
2026-02-20 00:30:25.034856 | 
2026-02-20 00:30:25.035037 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-02-20 00:30:25.050606 | controller | skipping: Conditional result was False
2026-02-20 00:30:25.058090 | 
2026-02-20 00:30:25.058164 | TASK [fetch-subunit-output : Remove the temporary file]
2026-02-20 00:30:25.073294 | controller | skipping: Conditional result was False
2026-02-20 00:30:25.080469 | 
2026-02-20 00:30:25.080541 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-02-20 00:30:25.105953 | controller | skipping: Conditional result was False
2026-02-20 00:30:25.133610 | 
2026-02-20 00:30:25.133678 | PLAY RECAP
2026-02-20 00:30:25.133723 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-02-20 00:30:25.133744 | 
2026-02-20 00:30:25.302854 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-02-20 00:30:25.303966 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-02-20 00:30:26.053060 | 
2026-02-20 00:30:26.053181 | PLAY [all:!appliance*]
2026-02-20 00:30:26.091474 | 
2026-02-20 00:30:26.091619 | TASK [unregister the node]
2026-02-20 00:30:26.626404 | controller | skipping: Conditional result was False
2026-02-20 00:30:26.640494 | 
2026-02-20 00:30:26.640738 | TASK [include_role : fetch-output]
2026-02-20 00:30:26.693105 | controller | ok
2026-02-20 00:30:26.751937 | 
2026-02-20 00:30:26.752135 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-20 00:30:26.833324 | controller | skipping: Conditional result was False
2026-02-20 00:30:26.842431 | 
2026-02-20 00:30:26.842580 | TASK [fetch-output : Set log path for single node]
2026-02-20 00:30:26.886755 | controller | ok
2026-02-20 00:30:26.895154 | 
2026-02-20 00:30:26.895300 | LOOP [fetch-output : Ensure local output dirs]
2026-02-20 00:30:27.357473 | controller -> localhost | ok: "/var/lib/zuul/builds/d1e88d9b857b40f59b52bd41fc2555e1/work/logs"
2026-02-20 00:30:27.585176 | controller -> localhost | changed: "/var/lib/zuul/builds/d1e88d9b857b40f59b52bd41fc2555e1/work/artifacts"
2026-02-20 00:30:27.857679 | controller -> localhost | changed: "/var/lib/zuul/builds/d1e88d9b857b40f59b52bd41fc2555e1/work/docs"
2026-02-20 00:30:27.870182 | 
2026-02-20 00:30:27.870324 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-20 00:30:28.546849 | controller | changed:
2026-02-20 00:30:28.547113 | controller | .d..t...... ./
2026-02-20 00:30:28.547156 | controller | cd+++++++++ controller/
2026-02-20 00:30:28.547205 | controller | changed: All items complete
2026-02-20 00:30:28.547235 | 
2026-02-20 00:30:29.347399 | controller | changed: .d..t...... ./
2026-02-20 00:30:29.863400 | controller | changed: .d..t...... ./
2026-02-20 00:30:29.888945 | 
2026-02-20 00:30:29.889151 | TASK [include_role : fetch-output-openshift]
2026-02-20 00:30:29.915316 | controller | skipping: Conditional result was False
2026-02-20 00:30:29.923767 | 
2026-02-20 00:30:29.923859 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-20 00:30:29.971056 | controller | skipping: Conditional result was False
2026-02-20 00:30:29.980267 | controller | skipping: Conditional result was False
2026-02-20 00:30:30.042081 | 
2026-02-20 00:30:30.042320 | PLAY [localhost]
2026-02-20 00:30:30.061232 | 
2026-02-20 00:30:30.061355 | TASK [Run Zuul manifest role]
2026-02-20 00:30:30.083340 | localhost | ok
2026-02-20 00:30:30.133821 | 
2026-02-20 00:30:30.133937 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-20 00:30:30.575794 | localhost | changed
2026-02-20 00:30:30.587381 | 
2026-02-20 00:30:30.587565 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-20 00:30:30.625442 | localhost | ok
2026-02-20 00:30:30.647456 | 
2026-02-20 00:30:30.647661 | TASK [Set zuul-log-path fact]
2026-02-20 00:30:30.678479 | localhost | ok
2026-02-20 00:30:30.714008 | 
2026-02-20 00:30:30.714213 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-20 00:30:30.744649 | localhost | ok
2026-02-20 00:30:30.753839 | 
2026-02-20 00:30:30.753948 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)]
2026-02-20 00:30:30.818524 | localhost | ok:
2026-02-20 00:30:30.818820 | localhost | {
2026-02-20 00:30:30.818876 | localhost |   "auth": {
2026-02-20 00:30:30.818924 | localhost |     "password": "IonWCwcuOFib&&<}wp^:[2G/",
2026-02-20 00:30:30.818963 | localhost |     "username": "9LF91O0RK7JU"
2026-02-20 00:30:30.818998 | localhost |   },
2026-02-20 00:30:30.819086 | localhost |   "profile": "rackspace",
2026-02-20 00:30:30.819125 | localhost |   "region_name": "IAD"
2026-02-20 00:30:30.819159 | localhost | }
2026-02-20 00:30:30.819207 | 
2026-02-20 00:30:30.828382 | 
2026-02-20 00:30:30.828518 | TASK [Run upload-logs-failover role (S3 then Swift)]
2026-02-20 00:30:30.850445 | localhost | Output suppressed because no_log was given
2026-02-20 00:30:30.881223 | 
2026-02-20 00:30:30.881401 | TASK [upload-logs-failover : Initialize control vars]
2026-02-20 00:30:30.915173 | localhost | ok
2026-02-20 00:30:30.960241 | 
2026-02-20 00:30:30.960392 | LOOP [upload-logs-failover : Perform log upload]
2026-02-20 00:30:30.986382 | localhost | Output suppressed because no_log was given
2026-02-20 00:30:30.995760 | localhost | included: /var/lib/zuul/builds/d1e88d9b857b40f59b52bd41fc2555e1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml
2026-02-20 00:30:31.002899 | 
2026-02-20 00:30:31.003066 | LOOP [upload-logs-failover : Unpack vars for swift upload]
2026-02-20 00:30:31.100907 | localhost | Output suppressed because no_log was given
2026-02-20 00:30:31.117670 | 
2026-02-20 00:30:31.117937 | TASK [Upload logs to swift]
2026-02-20 00:30:31.148496 | localhost | ok
2026-02-20 00:30:31.239903 | 
2026-02-20 00:30:31.240086 | TASK [Set zuul-log-path fact]
2026-02-20 00:30:31.286135 | localhost | skipping: Conditional result was False
2026-02-20 00:30:31.291760 | 
2026-02-20 00:30:31.291829 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-02-20 00:30:31.683439 | localhost -> localhost | ok: Runtime: 0:00:00.009345
2026-02-20 00:30:31.696130 | 
2026-02-20 00:30:31.696661 | TASK [upload-logs-swift : Upload logs to swift]