2025-11-11 00:07:24.224709 | Job console starting... 2025-11-11 00:07:24.235476 | Updating repositories 2025-11-11 00:07:24.457751 | Preparing job workspace 2025-11-11 00:07:30.707377 | Running Ansible setup... 2025-11-11 00:07:35.935536 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-11 00:07:36.583750 | 2025-11-11 00:07:36.583882 | PLAY [localhost] 2025-11-11 00:07:36.593504 | 2025-11-11 00:07:36.593634 | TASK [Gathering Facts] 2025-11-11 00:07:37.678440 | localhost | ok 2025-11-11 00:07:37.693261 | 2025-11-11 00:07:37.693403 | TASK [Setup log path fact] 2025-11-11 00:07:37.712175 | localhost | ok 2025-11-11 00:07:37.726148 | 2025-11-11 00:07:37.726273 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-11 00:07:37.756163 | localhost | ok 2025-11-11 00:07:37.767528 | 2025-11-11 00:07:37.767634 | TASK [emit-job-header : Print job information] 2025-11-11 00:07:37.820033 | # Job Information 2025-11-11 00:07:37.820314 | Ansible Version: 2.15.12 2025-11-11 00:07:37.820363 | Job: ansible-test-sanity-docker-stable-2.11 2025-11-11 00:07:37.820393 | Pipeline: periodic 2025-11-11 00:07:37.820420 | Executor: ze01.softwarefactory-project.io 2025-11-11 00:07:37.820445 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-11-11 00:07:37.820474 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/b20/ansible/b20b897140484866b781f41ad9151dee/ 2025-11-11 00:07:37.820502 | Event ID: 227e4e6828f94fb3a69b0e687d5dfdf5 2025-11-11 00:07:37.825959 | 2025-11-11 00:07:37.826092 | LOOP [emit-job-header : Print node information] 2025-11-11 00:07:37.947461 | localhost | ok: 2025-11-11 00:07:37.947616 | localhost | # Node Information 2025-11-11 00:07:37.947644 | localhost | Inventory Hostname: controller 2025-11-11 00:07:37.947664 | localhost | Hostname: np0005517394 2025-11-11 00:07:37.947683 | localhost | Username: zuul 2025-11-11 00:07:37.947705 | localhost | Distro: Fedora 37 2025-11-11 00:07:37.947724 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2025-11-11 00:07:37.947742 | localhost | Region: ca-ymq-1 2025-11-11 00:07:37.947758 | localhost | Label: ansible-fedora-37-1vcpu 2025-11-11 00:07:37.947774 | localhost | Product Name: OpenStack Nova 2025-11-11 00:07:37.947791 | localhost | Interface IP: 162.253.55.206 2025-11-11 00:07:37.965602 | 2025-11-11 00:07:37.965726 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-11 00:07:38.354809 | localhost -> localhost | changed 2025-11-11 00:07:38.360374 | 2025-11-11 00:07:38.360448 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-11 00:07:39.347689 | localhost -> localhost | changed 2025-11-11 00:07:39.368086 | 2025-11-11 00:07:39.368175 | PLAY [all:!appliance*] 2025-11-11 00:07:39.384593 | 2025-11-11 00:07:39.384668 | TASK [include_role : start-zuul-console] 2025-11-11 00:07:39.403299 | controller | ok 2025-11-11 00:07:39.417259 | 2025-11-11 00:07:39.417330 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-11 00:07:39.780587 | controller | ok 2025-11-11 00:07:39.803141 | 2025-11-11 00:07:39.803256 | TASK [use-our-mirror : Retrieve the IP address] 2025-11-11 00:07:42.016218 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-11-11 00:07:42.023070 | 2025-11-11 00:07:42.023148 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-11-11 00:07:42.554504 | controller | skipping: Conditional result was False 2025-11-11 00:07:42.567786 | 2025-11-11 00:07:42.567926 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-11-11 00:07:42.586315 | controller | skipping: Conditional result was False 2025-11-11 00:07:42.595247 | 2025-11-11 00:07:42.595460 | TASK [use-our-mirror : Create the podman configuration directory] 2025-11-11 00:07:42.611646 | controller | skipping: Conditional result was False 2025-11-11 00:07:42.626920 | 2025-11-11 00:07:42.627166 | TASK [use-our-mirror : Copy the podman configuration] 2025-11-11 00:07:42.657356 | controller | skipping: Conditional result was False 2025-11-11 00:07:42.667763 | 2025-11-11 00:07:42.667866 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-11-11 00:07:42.682453 | controller | skipping: Conditional result was False 2025-11-11 00:07:42.690761 | 2025-11-11 00:07:42.690908 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-11-11 00:07:42.708956 | controller | skipping: Conditional result was False 2025-11-11 00:07:42.722523 | 2025-11-11 00:07:42.722644 | TASK [Disable Fedora Modular] 2025-11-11 00:07:42.971239 | controller | changed 2025-11-11 00:07:42.983595 | 2025-11-11 00:07:42.983768 | TASK [Enable EPEL] 2025-11-11 00:07:43.000459 | controller | skipping: Conditional result was False 2025-11-11 00:07:43.015657 | 2025-11-11 00:07:43.015840 | TASK [Register the RHEL node] 2025-11-11 00:07:43.570871 | 2025-11-11 00:07:43.571069 | TASK [Show the subscription-manager status] 2025-11-11 00:07:44.134926 | controller | skipping: Conditional result was False 2025-11-11 00:07:44.148662 | 2025-11-11 00:07:44.148828 | TASK [Enable EPEL on RHEL] 2025-11-11 00:07:44.710146 | controller | skipping: Conditional result was False 2025-11-11 00:07:44.728715 | 2025-11-11 00:07:44.728861 | TASK [Install git and tox] 2025-11-11 00:09:16.425779 | controller | changed 2025-11-11 00:09:16.435841 | 2025-11-11 00:09:16.435941 | TASK [include_role : prepare-workspace] 2025-11-11 00:09:16.459182 | controller | ok 2025-11-11 00:09:16.490236 | 2025-11-11 00:09:16.490363 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-11-11 00:09:16.727948 | controller | ok 2025-11-11 00:09:16.743581 | 2025-11-11 00:09:16.743734 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-11-11 00:09:32.588107 | controller | Output suppressed because no_log was given 2025-11-11 00:09:32.633104 | 2025-11-11 00:09:32.633214 | TASK [include_role : prepare-workspace-openshift] 2025-11-11 00:09:32.657208 | controller | skipping: Conditional result was False 2025-11-11 00:09:32.678978 | 2025-11-11 00:09:32.679081 | PLAY [all:!appliance] 2025-11-11 00:09:32.702836 | 2025-11-11 00:09:32.702933 | TASK [Run add-build-sshkey role (RSA)] 2025-11-11 00:09:32.723507 | controller | ok 2025-11-11 00:09:32.739926 | 2025-11-11 00:09:32.740043 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-11 00:09:32.990368 | controller -> localhost | ok 2025-11-11 00:09:32.997848 | 2025-11-11 00:09:32.997941 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-11 00:09:33.030249 | controller | ok 2025-11-11 00:09:33.048827 | controller | included: /var/lib/zuul/builds/b20b897140484866b781f41ad9151dee/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-11 00:09:33.055336 | 2025-11-11 00:09:33.055422 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-11 00:09:33.547395 | controller -> localhost | Generating public/private rsa key pair. 2025-11-11 00:09:33.547619 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/b20b897140484866b781f41ad9151dee/work/b20b897140484866b781f41ad9151dee_id_rsa. 2025-11-11 00:09:33.547661 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/b20b897140484866b781f41ad9151dee/work/b20b897140484866b781f41ad9151dee_id_rsa.pub. 2025-11-11 00:09:33.547693 | controller -> localhost | The key fingerprint is: 2025-11-11 00:09:33.547723 | controller -> localhost | SHA256:k3OICQhrtC3IsHbiYvn2W6gK56r7Miw6JdlC4pH12LI zuul-build-sshkey 2025-11-11 00:09:33.547751 | controller -> localhost | The key's randomart image is: 2025-11-11 00:09:33.547780 | controller -> localhost | +---[RSA 2048]----+ 2025-11-11 00:09:33.547808 | controller -> localhost | |o. | 2025-11-11 00:09:33.547836 | controller -> localhost | |=+oo | 2025-11-11 00:09:33.547864 | controller -> localhost | |+B+o= | 2025-11-11 00:09:33.547891 | controller -> localhost | |=+=o + o o | 2025-11-11 00:09:33.547918 | controller -> localhost | |=*. o o S . | 2025-11-11 00:09:33.547959 | controller -> localhost | |*.+E . + | 2025-11-11 00:09:33.547995 | controller -> localhost | |o+.o . . | 2025-11-11 00:09:33.548055 | controller -> localhost | |*+. o . | 2025-11-11 00:09:33.548087 | controller -> localhost | |OO+. o. | 2025-11-11 00:09:33.548119 | controller -> localhost | +----[SHA256]-----+ 2025-11-11 00:09:33.548184 | controller -> localhost | ok: Runtime: 0:00:00.128020 2025-11-11 00:09:33.559547 | 2025-11-11 00:09:33.559705 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-11 00:09:33.603862 | controller | ok 2025-11-11 00:09:33.620875 | controller | included: /var/lib/zuul/builds/b20b897140484866b781f41ad9151dee/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-11 00:09:33.637419 | 2025-11-11 00:09:33.637561 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-11 00:09:33.664111 | controller | skipping: Conditional result was False 2025-11-11 00:09:33.675311 | 2025-11-11 00:09:33.675462 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-11 00:09:34.146326 | controller | changed 2025-11-11 00:09:34.151943 | 2025-11-11 00:09:34.152035 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-11 00:09:34.383854 | controller | ok 2025-11-11 00:09:34.399556 | 2025-11-11 00:09:34.399744 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-11 00:09:35.029252 | controller | changed 2025-11-11 00:09:35.038442 | 2025-11-11 00:09:35.038575 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-11 00:09:35.695122 | controller | changed 2025-11-11 00:09:35.701388 | 2025-11-11 00:09:35.701485 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-11 00:09:35.726973 | controller | skipping: Conditional result was False 2025-11-11 00:09:35.734010 | 2025-11-11 00:09:35.734154 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-11 00:09:36.209593 | controller -> localhost | changed 2025-11-11 00:09:36.240991 | 2025-11-11 00:09:36.241215 | TASK [add-build-sshkey : Add back temp key] 2025-11-11 00:09:36.543783 | controller -> localhost | Identity added: /var/lib/zuul/builds/b20b897140484866b781f41ad9151dee/work/b20b897140484866b781f41ad9151dee_id_rsa (zuul-build-sshkey) 2025-11-11 00:09:36.543986 | controller -> localhost | ok: Runtime: 0:00:00.008011 2025-11-11 00:09:36.550655 | 2025-11-11 00:09:36.550718 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-11 00:09:36.887867 | controller | ok 2025-11-11 00:09:36.895561 | 2025-11-11 00:09:36.895646 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-11 00:09:36.931197 | controller | skipping: Conditional result was False 2025-11-11 00:09:36.949610 | 2025-11-11 00:09:36.949755 | TASK [Run add-build-sshkey role (ECDSA)] 2025-11-11 00:09:36.982911 | controller | ok 2025-11-11 00:09:37.008228 | 2025-11-11 00:09:37.008376 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-11 00:09:37.247462 | controller -> localhost | ok 2025-11-11 00:09:37.256954 | 2025-11-11 00:09:37.257115 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-11 00:09:37.290412 | controller | ok 2025-11-11 00:09:37.302515 | controller | included: /var/lib/zuul/builds/b20b897140484866b781f41ad9151dee/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-11 00:09:37.309517 | 2025-11-11 00:09:37.309605 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-11 00:09:37.588899 | controller -> localhost | Generating public/private ecdsa key pair. 2025-11-11 00:09:37.589222 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/b20b897140484866b781f41ad9151dee/work/b20b897140484866b781f41ad9151dee_id_ecdsa. 2025-11-11 00:09:37.589282 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/b20b897140484866b781f41ad9151dee/work/b20b897140484866b781f41ad9151dee_id_ecdsa.pub. 2025-11-11 00:09:37.589343 | controller -> localhost | The key fingerprint is: 2025-11-11 00:09:37.589388 | controller -> localhost | SHA256:FyS8iGGg/tApM/ulDauhrswCQAlzvvHI7CFjd2m2bac zuul-build-sshkey 2025-11-11 00:09:37.589429 | controller -> localhost | The key's randomart image is: 2025-11-11 00:09:37.589469 | controller -> localhost | +---[ECDSA 521]---+ 2025-11-11 00:09:37.589508 | controller -> localhost | |+ +. .. . | 2025-11-11 00:09:37.589545 | controller -> localhost | | B o .o | 2025-11-11 00:09:37.589583 | controller -> localhost | |o o. o . .. | 2025-11-11 00:09:37.589620 | controller -> localhost | |oo.=o o . . | 2025-11-11 00:09:37.589658 | controller -> localhost | |+B*+.= S . | 2025-11-11 00:09:37.589695 | controller -> localhost | |o+O.+ o . | 2025-11-11 00:09:37.589732 | controller -> localhost | |.o.o o o . | 2025-11-11 00:09:37.589768 | controller -> localhost | |= o * . o | 2025-11-11 00:09:37.589805 | controller -> localhost | |*=.+ . E | 2025-11-11 00:09:37.589842 | controller -> localhost | +----[SHA256]-----+ 2025-11-11 00:09:37.589930 | controller -> localhost | ok: Runtime: 0:00:00.007803 2025-11-11 00:09:37.604600 | 2025-11-11 00:09:37.604734 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-11 00:09:37.644174 | controller | ok 2025-11-11 00:09:37.662059 | controller | included: /var/lib/zuul/builds/b20b897140484866b781f41ad9151dee/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-11 00:09:37.677752 | 2025-11-11 00:09:37.677864 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-11 00:09:37.702707 | controller | skipping: Conditional result was False 2025-11-11 00:09:37.712421 | 2025-11-11 00:09:37.712531 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-11 00:09:37.973094 | controller | changed 2025-11-11 00:09:37.983183 | 2025-11-11 00:09:37.983329 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-11 00:09:38.196328 | controller | ok 2025-11-11 00:09:38.212571 | 2025-11-11 00:09:38.212742 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-11 00:09:38.869944 | controller | changed 2025-11-11 00:09:38.884789 | 2025-11-11 00:09:38.884970 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-11 00:09:39.508302 | controller | changed 2025-11-11 00:09:39.521214 | 2025-11-11 00:09:39.521358 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-11 00:09:39.548114 | controller | skipping: Conditional result was False 2025-11-11 00:09:39.600862 | 2025-11-11 00:09:39.600989 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-11 00:09:39.891116 | controller -> localhost | changed 2025-11-11 00:09:39.904736 | 2025-11-11 00:09:39.904848 | TASK [add-build-sshkey : Add back temp key] 2025-11-11 00:09:40.152646 | controller -> localhost | Identity added: /var/lib/zuul/builds/b20b897140484866b781f41ad9151dee/work/b20b897140484866b781f41ad9151dee_id_ecdsa (zuul-build-sshkey) 2025-11-11 00:09:40.153316 | controller -> localhost | ok: Runtime: 0:00:00.008240 2025-11-11 00:09:40.161108 | 2025-11-11 00:09:40.161408 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-11 00:09:40.384845 | controller | ok 2025-11-11 00:09:40.402281 | 2025-11-11 00:09:40.402711 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-11 00:09:40.430603 | controller | skipping: Conditional result was False 2025-11-11 00:09:40.450109 | 2025-11-11 00:09:40.450260 | TASK [include_role : remove-zuul-sshkey] 2025-11-11 00:09:40.475842 | controller | skipping: Conditional result was False 2025-11-11 00:09:40.485639 | 2025-11-11 00:09:40.485775 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-11-11 00:09:40.701245 | controller | ok: "logs" 2025-11-11 00:09:40.701543 | controller | ok: All items complete 2025-11-11 00:09:40.701570 | 2025-11-11 00:09:40.916697 | controller | ok: "artifacts" 2025-11-11 00:09:41.101584 | controller | ok: "docs" 2025-11-11 00:09:41.114335 | 2025-11-11 00:09:41.114464 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-11-11 00:09:41.345202 | controller | changed: "logs" 2025-11-11 00:09:41.561968 | controller | changed: "artifacts" 2025-11-11 00:09:41.759993 | controller | changed: "docs" 2025-11-11 00:09:41.794580 | 2025-11-11 00:09:41.794724 | PLAY RECAP 2025-11-11 00:09:41.794795 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-11-11 00:09:41.794853 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-11 00:09:41.794887 | 2025-11-11 00:09:41.939317 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-11-11 00:09:41.941284 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-11 00:09:42.578675 | 2025-11-11 00:09:42.578827 | PLAY [all] 2025-11-11 00:09:42.607662 | 2025-11-11 00:09:42.607973 | TASK [Install binary dependencies] 2025-11-11 00:09:42.658512 | controller | ok 2025-11-11 00:09:42.683373 | 2025-11-11 00:09:42.683532 | TASK [bindep : Include find tasks] 2025-11-11 00:09:42.724614 | controller | ok 2025-11-11 00:09:42.734129 | controller | included: /var/lib/zuul/builds/b20b897140484866b781f41ad9151dee/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-11-11 00:09:42.741197 | 2025-11-11 00:09:42.741293 | TASK [bindep : Look for bindep.txt] 2025-11-11 00:09:43.122455 | controller | ok 2025-11-11 00:09:43.134953 | 2025-11-11 00:09:43.135144 | TASK [bindep : Define bindep_file fact] 2025-11-11 00:09:43.162514 | controller | skipping: Conditional result was False 2025-11-11 00:09:43.177253 | 2025-11-11 00:09:43.177410 | TASK [bindep : Look for other-requirements.txt] 2025-11-11 00:09:43.395965 | controller | ok 2025-11-11 00:09:43.403703 | 2025-11-11 00:09:43.403835 | TASK [bindep : Define bindep_file fact] 2025-11-11 00:09:43.429911 | controller | skipping: Conditional result was False 2025-11-11 00:09:43.438822 | 2025-11-11 00:09:43.438938 | TASK [bindep : Look for bindep fallback file] 2025-11-11 00:09:43.463930 | controller | skipping: Conditional result was False 2025-11-11 00:09:43.473589 | 2025-11-11 00:09:43.473708 | TASK [bindep : Define bindep_file fact] 2025-11-11 00:09:43.498854 | controller | skipping: Conditional result was False 2025-11-11 00:09:43.507363 | 2025-11-11 00:09:43.507460 | TASK [bindep : Include bindep tasks] 2025-11-11 00:09:43.532429 | controller | skipping: Conditional result was False 2025-11-11 00:09:43.539996 | 2025-11-11 00:09:43.540147 | TASK [bindep : Include install tasks] 2025-11-11 00:09:43.564408 | controller | skipping: Conditional result was False 2025-11-11 00:09:43.577136 | 2025-11-11 00:09:43.577289 | LOOP [bindep : Include package tasks] 2025-11-11 00:09:43.635425 | 2025-11-11 00:09:43.635592 | TASK [Run test-setup role] 2025-11-11 00:09:43.656503 | controller | ok 2025-11-11 00:09:43.678118 | 2025-11-11 00:09:43.678428 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-11-11 00:09:43.893957 | controller | ok 2025-11-11 00:09:43.902803 | 2025-11-11 00:09:43.902923 | TASK [test-setup : Run tools/test-setup.sh] 2025-11-11 00:09:44.445572 | controller | skipping: Conditional result was False 2025-11-11 00:09:44.504049 | 2025-11-11 00:09:44.504208 | PLAY RECAP 2025-11-11 00:09:44.504423 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-11 00:09:44.504459 | 2025-11-11 00:09:44.647133 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-11-11 00:09:44.649120 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-11 00:09:45.291769 | 2025-11-11 00:09:45.291905 | PLAY [controller] 2025-11-11 00:09:45.315859 | 2025-11-11 00:09:45.315973 | TASK [Create the /root directory] 2025-11-11 00:09:45.748333 | controller | ok 2025-11-11 00:09:45.764472 | 2025-11-11 00:09:45.764806 | TASK [Install glibc-langpack-en] 2025-11-11 00:09:49.655478 | controller | ok: Nothing to do 2025-11-11 00:09:49.664916 | 2025-11-11 00:09:49.665064 | TASK [Ensure controller directory exists] 2025-11-11 00:09:49.879478 | controller | changed 2025-11-11 00:09:49.889828 | 2025-11-11 00:09:49.889987 | TASK [Install container runtime] 2025-11-11 00:09:49.961124 | controller | ok 2025-11-11 00:09:50.037873 | 2025-11-11 00:09:50.038007 | LOOP [ensure-docker : Gather variables for each operating system] 2025-11-11 00:09:50.081409 | controller | ok: "/var/lib/zuul/builds/b20b897140484866b781f41ad9151dee/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2025-11-11 00:09:50.093459 | 2025-11-11 00:09:50.093629 | TASK [ensure-docker : Sanity check for distro selection] 2025-11-11 00:09:50.109807 | controller | skipping: Conditional result was False 2025-11-11 00:09:50.119563 | 2025-11-11 00:09:50.119695 | TASK [ensure-docker : Sanity check for distro install] 2025-11-11 00:09:50.145674 | controller | skipping: Conditional result was False 2025-11-11 00:09:50.154686 | 2025-11-11 00:09:50.154838 | TASK [ensure-docker : Sanity check for upstream install] 2025-11-11 00:09:50.180387 | controller | skipping: Conditional result was False 2025-11-11 00:09:50.187249 | 2025-11-11 00:09:50.187367 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2025-11-11 00:09:50.212355 | controller | skipping: Conditional result was False 2025-11-11 00:09:50.222340 | 2025-11-11 00:09:50.222479 | TASK [ensure-docker : Include tasks for docker install] 2025-11-11 00:09:50.244240 | controller | ok 2025-11-11 00:09:50.254028 | controller | included: /var/lib/zuul/builds/b20b897140484866b781f41ad9151dee/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2025-11-11 00:09:50.263301 | 2025-11-11 00:09:50.263391 | TASK [ensure-docker : Not Implemented] 2025-11-11 00:09:50.277924 | controller | skipping: Conditional result was False 2025-11-11 00:09:50.286709 | 2025-11-11 00:09:50.286819 | TASK [Set up docker mirrors] 2025-11-11 00:09:50.306564 | controller | ok 2025-11-11 00:09:50.331053 | 2025-11-11 00:09:50.331206 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2025-11-11 00:09:50.356602 | controller | skipping: Conditional result was False 2025-11-11 00:09:50.363963 | 2025-11-11 00:09:50.364108 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2025-11-11 00:09:50.388225 | controller | skipping: Conditional result was False 2025-11-11 00:09:50.398266 | 2025-11-11 00:09:50.398401 | TASK [use-docker-mirror : Set docker_mirror fact] 2025-11-11 00:09:50.424683 | controller | skipping: Conditional result was False 2025-11-11 00:09:50.433359 | 2025-11-11 00:09:50.433496 | TASK [use-docker-mirror : Set up docker mirrors] 2025-11-11 00:09:50.458378 | controller | skipping: Conditional result was False 2025-11-11 00:09:50.473616 | 2025-11-11 00:09:50.473738 | TASK [Add all repositories] 2025-11-11 00:09:50.494173 | controller | ok 2025-11-11 00:09:50.521281 | 2025-11-11 00:09:50.521396 | LOOP [ensure-package-repositories : Install repositories] 2025-11-11 00:09:50.551349 | controller | ok: "/var/lib/zuul/builds/b20b897140484866b781f41ad9151dee/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2025-11-11 00:09:50.551560 | controller | ok: All items complete 2025-11-11 00:09:50.551598 | 2025-11-11 00:09:50.568115 | controller | included: /var/lib/zuul/builds/b20b897140484866b781f41ad9151dee/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2025-11-11 00:09:50.623232 | 2025-11-11 00:09:50.623377 | TASK [ensure-package-repositories : Create GPG key temp directory] 2025-11-11 00:09:50.934500 | controller | changed 2025-11-11 00:09:50.942393 | 2025-11-11 00:09:50.942493 | LOOP [ensure-package-repositories : Upload GPG keys] 2025-11-11 00:09:51.872437 | controller | changed: 2025-11-11 00:09:51.872631 | controller | { 2025-11-11 00:09:51.872663 | 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" 2025-11-11 00:09:51.872692 | controller | } 2025-11-11 00:09:51.881105 | 2025-11-11 00:09:51.881207 | LOOP [ensure-package-repositories : Import GPG keys] 2025-11-11 00:09:52.527433 | controller | changed: 2025-11-11 00:09:52.527587 | controller | { 2025-11-11 00:09:52.527622 | 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" 2025-11-11 00:09:52.527653 | controller | } 2025-11-11 00:09:52.527690 | controller | changed: All items complete 2025-11-11 00:09:52.527713 | 2025-11-11 00:09:52.551207 | 2025-11-11 00:09:52.551445 | TASK [ensure-package-repositories : Remove GPG temp directory] 2025-11-11 00:09:52.783199 | controller | changed 2025-11-11 00:09:52.793751 | 2025-11-11 00:09:52.793919 | LOOP [ensure-package-repositories : Add repositories] 2025-11-11 00:09:53.157295 | controller | changed: 2025-11-11 00:09:53.157624 | controller | { 2025-11-11 00:09:53.157684 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2025-11-11 00:09:53.157729 | controller | "description": "Docker CE Stable - $basearch", 2025-11-11 00:09:53.157769 | controller | "gpgcheck": true, 2025-11-11 00:09:53.157808 | controller | "name": "docker-ce-stable" 2025-11-11 00:09:53.157847 | controller | } 2025-11-11 00:09:53.193981 | 2025-11-11 00:09:53.194249 | TASK [ensure-docker : Install pre-reqs] 2025-11-11 00:09:55.978884 | controller | ok: Nothing to do 2025-11-11 00:09:55.987821 | 2025-11-11 00:09:55.987933 | TASK [ensure-docker : Remove packages] 2025-11-11 00:09:57.325903 | controller | ok: Nothing to do 2025-11-11 00:09:57.335288 | 2025-11-11 00:09:57.335398 | TASK [ensure-docker : Install upstream docker using package] 2025-11-11 00:10:44.284501 | controller | changed 2025-11-11 00:10:44.291270 | 2025-11-11 00:10:44.291376 | TASK [ensure-docker : Setup docker] 2025-11-11 00:10:44.313990 | controller | ok 2025-11-11 00:10:44.330056 | controller | included: /var/lib/zuul/builds/b20b897140484866b781f41ad9151dee/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2025-11-11 00:10:44.348118 | 2025-11-11 00:10:44.348254 | TASK [ensure-docker : Ensure "docker" group exists] 2025-11-11 00:10:44.751413 | controller | ok 2025-11-11 00:10:44.760938 | 2025-11-11 00:10:44.761072 | TASK [ensure-docker : Add user to docker group] 2025-11-11 00:10:45.242157 | controller | changed 2025-11-11 00:10:45.249459 | 2025-11-11 00:10:45.249566 | TASK [Update docker daemon proxy configuration] 2025-11-11 00:10:45.295578 | controller | skipping: Conditional result was False 2025-11-11 00:10:45.316525 | 2025-11-11 00:10:45.316657 | TASK [Lower default MTU] 2025-11-11 00:10:45.345335 | controller | skipping: Conditional result was False 2025-11-11 00:10:45.360408 | 2025-11-11 00:10:45.360541 | TASK [ensure-docker : Get the status of the docket.socket unit] 2025-11-11 00:10:45.740891 | controller | inactive 2025-11-11 00:10:45.945321 | controller | ok: Runtime: 0:00:00.013623 2025-11-11 00:10:45.952199 | 2025-11-11 00:10:45.952350 | TASK [ensure-docker : Get the status of the docket unit] 2025-11-11 00:10:46.186826 | controller | inactive 2025-11-11 00:10:46.490394 | controller | ok: Runtime: 0:00:00.008654 2025-11-11 00:10:46.505496 | 2025-11-11 00:10:46.505677 | TASK [ensure-docker : Restart docker] 2025-11-11 00:10:46.541220 | Trigger a Docker restart 2025-11-11 00:10:46.548738 | 2025-11-11 00:10:46.548868 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2025-11-11 00:10:46.583502 | 2025-11-11 00:10:46.583656 | TASK [ensure-docker : Install docker-compose] 2025-11-11 00:10:46.599232 | controller | skipping: Conditional result was False 2025-11-11 00:10:46.608791 | 2025-11-11 00:10:46.608942 | TASK [ensure-docker : Flush handlers before role exit] 2025-11-11 00:10:46.626447 | 2025-11-11 00:10:46.626596 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2025-11-11 00:10:48.083829 | controller | changed 2025-11-11 00:10:48.100177 | 2025-11-11 00:10:48.100382 | TASK [ensure-docker : Assure docker service is running] 2025-11-11 00:10:49.838154 | controller | changed 2025-11-11 00:10:49.856523 | 2025-11-11 00:10:49.856718 | TASK [ensure-docker : Assure docker.socket service is running] 2025-11-11 00:10:50.859869 | controller | ok 2025-11-11 00:10:50.878566 | 2025-11-11 00:10:50.878736 | TASK [ensure-docker : Correct group ownership on docker sock] 2025-11-11 00:10:51.104259 | controller | ok 2025-11-11 00:10:51.112065 | 2025-11-11 00:10:51.112189 | TASK [ensure-docker : Validate ability to talk with docker] 2025-11-11 00:10:51.673157 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2025-11-11 00:10:52.152329 | controller | ok: Runtime: 0:00:00.039791 2025-11-11 00:10:52.161236 | 2025-11-11 00:10:52.161374 | TASK [ensure-docker : Get version details] 2025-11-11 00:10:52.718766 | controller | Client: Docker Engine - Community 2025-11-11 00:10:52.720347 | controller | Version: 24.0.7 2025-11-11 00:10:52.720366 | controller | API version: 1.43 2025-11-11 00:10:52.720372 | controller | Go version: go1.20.10 2025-11-11 00:10:52.720428 | controller | Git commit: afdd53b 2025-11-11 00:10:52.720435 | controller | Built: Thu Oct 26 09:09:39 2023 2025-11-11 00:10:52.720440 | controller | OS/Arch: linux/amd64 2025-11-11 00:10:52.720446 | controller | Context: default 2025-11-11 00:10:52.720452 | controller | 2025-11-11 00:10:52.720456 | controller | Server: Docker Engine - Community 2025-11-11 00:10:52.720461 | controller | Engine: 2025-11-11 00:10:52.720465 | controller | Version: 24.0.7 2025-11-11 00:10:52.720469 | controller | API version: 1.43 (minimum version 1.12) 2025-11-11 00:10:52.720473 | controller | Go version: go1.20.10 2025-11-11 00:10:52.720477 | controller | Git commit: 311b9ff 2025-11-11 00:10:52.720481 | controller | Built: Thu Oct 26 09:08:11 2023 2025-11-11 00:10:52.720485 | controller | OS/Arch: linux/amd64 2025-11-11 00:10:52.720489 | controller | Experimental: false 2025-11-11 00:10:52.720493 | controller | containerd: 2025-11-11 00:10:52.720497 | controller | Version: 1.6.25 2025-11-11 00:10:52.720501 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2025-11-11 00:10:52.720505 | controller | runc: 2025-11-11 00:10:52.720510 | controller | Version: 1.1.10 2025-11-11 00:10:52.720514 | controller | GitCommit: v1.1.10-0-g18a0cb0 2025-11-11 00:10:52.720519 | controller | docker-init: 2025-11-11 00:10:52.720523 | controller | Version: 0.19.0 2025-11-11 00:10:52.720527 | controller | GitCommit: de40ad0 2025-11-11 00:10:53.213273 | controller | ok: Runtime: 0:00:00.078018 2025-11-11 00:10:53.224388 | 2025-11-11 00:10:53.225428 | TASK [ensure-docker : Dump installed docker details] 2025-11-11 00:10:53.250345 | {'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': '2025-11-11 00:10:52.642689', 'end': '2025-11-11 00:10:52.720707', 'delta': '0:00:00.078018', 'msg': '', 'zuul_log_id': 'fa163ec2-ffbe-1c7d-2bac-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} 2025-11-11 00:10:53.259738 | 2025-11-11 00:10:53.259877 | TASK [Use buildset registry] 2025-11-11 00:10:53.275247 | controller | skipping: Conditional result was False 2025-11-11 00:10:53.292795 | 2025-11-11 00:10:53.292956 | TASK [Ensure python3.8 is present] 2025-11-11 00:10:53.307981 | controller | skipping: Conditional result was False 2025-11-11 00:10:53.315089 | 2025-11-11 00:10:53.315209 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-11-11 00:10:53.337285 | controller | ok 2025-11-11 00:10:53.360324 | 2025-11-11 00:10:53.360453 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-11-11 00:10:55.426621 | controller | ok: Nothing to do 2025-11-11 00:10:55.440412 | 2025-11-11 00:10:55.440601 | TASK [our-ensure-python : Also install python3-devel] 2025-11-11 00:11:06.390821 | controller | changed 2025-11-11 00:11:06.407927 | 2025-11-11 00:11:06.408098 | TASK [Run ensure-virtualenv role] 2025-11-11 00:11:06.438827 | controller | ok 2025-11-11 00:11:06.463921 | 2025-11-11 00:11:06.464064 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-11-11 00:11:06.687627 | controller | /usr/bin/virtualenv 2025-11-11 00:11:06.996967 | controller | ok: Runtime: 0:00:00.004287 2025-11-11 00:11:07.011375 | 2025-11-11 00:11:07.011529 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-11-11 00:11:07.033409 | controller | skipping: Conditional result was False 2025-11-11 00:11:07.033709 | controller | changed: All items complete 2025-11-11 00:11:07.033739 | 2025-11-11 00:11:07.066796 | 2025-11-11 00:11:07.067083 | TASK [Find the full path of the Python interpreter] 2025-11-11 00:11:07.288494 | controller | /usr/bin/python3.9 2025-11-11 00:11:07.613802 | controller | ok 2025-11-11 00:11:07.620931 | 2025-11-11 00:11:07.621070 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-11-11 00:11:09.140866 | controller | created virtual environment CPython3.9.18.final.0-64 in 772ms 2025-11-11 00:11:09.157794 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2025-11-11 00:11:09.157848 | 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) 2025-11-11 00:11:09.157859 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-11-11 00:11:09.157872 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-11-11 00:11:09.657112 | controller | changed 2025-11-11 00:11:09.664166 | 2025-11-11 00:11:09.664266 | TASK [Set selinux package] 2025-11-11 00:11:09.686233 | controller | ok 2025-11-11 00:11:09.691614 | 2025-11-11 00:11:09.691676 | TASK [Set selinux package (Fedora)] 2025-11-11 00:11:09.742512 | controller | ok 2025-11-11 00:11:09.749007 | 2025-11-11 00:11:09.749139 | TASK [Install selinux into virtualenv] 2025-11-11 00:11:23.055117 | controller | Collecting selinux-please-lie-to-me 2025-11-11 00:11:35.315765 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-11-11 00:11:35.674066 | controller | Collecting setuptools<50.0.0 2025-11-11 00:11:35.689024 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-11-11 00:11:35.735051 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 20.0 MB/s eta 0:00:00 2025-11-11 00:11:35.815528 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-11-11 00:11:35.815679 | controller | Attempting uninstall: setuptools 2025-11-11 00:11:35.816342 | controller | Found existing installation: setuptools 62.6.0 2025-11-11 00:11:35.888105 | controller | Uninstalling setuptools-62.6.0: 2025-11-11 00:11:35.896564 | controller | Successfully uninstalled setuptools-62.6.0 2025-11-11 00:11:36.219265 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-11-11 00:11:47.690850 | controller | 2025-11-11 00:11:47.765082 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-11-11 00:11:47.765134 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-11-11 00:11:47.813734 | controller | ok: Runtime: 0:00:37.456791 2025-11-11 00:11:47.828496 | 2025-11-11 00:11:47.828640 | TASK [Install pytest-forked into virtualenv] 2025-11-11 00:11:59.926032 | controller | Collecting pytest-forked 2025-11-11 00:12:10.186166 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2025-11-11 00:12:10.342606 | controller | Collecting py 2025-11-11 00:12:10.346068 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2025-11-11 00:12:10.366577 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 5.1 MB/s eta 0:00:00 2025-11-11 00:12:10.512210 | controller | Collecting pytest>=3.10 2025-11-11 00:12:10.518619 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2025-11-11 00:12:10.529269 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 44.4 MB/s eta 0:00:00 2025-11-11 00:12:10.595376 | controller | Collecting packaging>=20 2025-11-11 00:12:10.598846 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-11-11 00:12:10.607773 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 9.2 MB/s eta 0:00:00 2025-11-11 00:12:10.637288 | controller | Collecting iniconfig>=1 2025-11-11 00:12:10.641019 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2025-11-11 00:12:10.694670 | controller | Collecting pygments>=2.7.2 2025-11-11 00:12:10.698028 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2025-11-11 00:12:10.722910 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 55.8 MB/s eta 0:00:00 2025-11-11 00:12:10.798857 | controller | Collecting exceptiongroup>=1 2025-11-11 00:12:10.803187 | controller | Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB) 2025-11-11 00:12:10.862839 | controller | Collecting pluggy<2,>=1.5 2025-11-11 00:12:10.866922 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-11-11 00:12:10.929435 | controller | Collecting tomli>=1 2025-11-11 00:12:10.933334 | controller | Downloading tomli-2.3.0-py3-none-any.whl (14 kB) 2025-11-11 00:12:10.997188 | controller | Collecting typing-extensions>=4.6.0 2025-11-11 00:12:11.001996 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2025-11-11 00:12:11.012166 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 5.7 MB/s eta 0:00:00 2025-11-11 00:12:11.084226 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2025-11-11 00:12:12.164156 | controller | Successfully installed exceptiongroup-1.3.0 iniconfig-2.1.0 packaging-25.0 pluggy-1.6.0 py-1.11.0 pygments-2.19.2 pytest-8.4.2 pytest-forked-1.6.0 tomli-2.3.0 typing-extensions-4.15.0 2025-11-11 00:12:12.167289 | controller | 2025-11-11 00:12:12.233314 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-11-11 00:12:12.233433 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-11-11 00:12:12.425889 | controller | ok: Runtime: 0:00:24.145262 2025-11-11 00:12:12.434634 | 2025-11-11 00:12:12.434769 | TASK [Update pip] 2025-11-11 00:12:12.942029 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2025-11-11 00:12:22.578003 | controller | Collecting pip 2025-11-11 00:12:34.768393 | controller | Downloading pip-25.3-py3-none-any.whl (1.8 MB) 2025-11-11 00:12:34.820514 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 37.8 MB/s eta 0:00:00 2025-11-11 00:12:34.880147 | controller | Installing collected packages: pip 2025-11-11 00:12:34.880270 | controller | Attempting uninstall: pip 2025-11-11 00:12:34.880879 | controller | Found existing installation: pip 22.2.2 2025-11-11 00:12:35.048022 | controller | Uninstalling pip-22.2.2: 2025-11-11 00:12:35.064670 | controller | Successfully uninstalled pip-22.2.2 2025-11-11 00:12:35.842191 | controller | Successfully installed pip-25.3 2025-11-11 00:12:35.987818 | controller | ok: Runtime: 0:00:23.266996 2025-11-11 00:12:35.994381 | 2025-11-11 00:12:35.994482 | TASK [Ensure pip temp directory exists in /var/tmp] 2025-11-11 00:12:36.202078 | controller | changed 2025-11-11 00:12:36.210220 | 2025-11-11 00:12:36.210350 | TASK [Install ansible into virtualenv] 2025-11-11 00:12:36.707206 | controller | Processing ./src/github.com/ansible/ansible 2025-11-11 00:12:36.711229 | controller | Installing build dependencies: started 2025-11-11 00:12:59.653834 | controller | Installing build dependencies: finished with status 'done' 2025-11-11 00:12:59.655466 | controller | Getting requirements to build wheel: started 2025-11-11 00:13:00.244631 | controller | Getting requirements to build wheel: finished with status 'done' 2025-11-11 00:13:00.247043 | controller | Preparing metadata (pyproject.toml): started 2025-11-11 00:13:00.699744 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2025-11-11 00:13:35.865118 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2025-11-11 00:13:47.795097 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-11-11 00:13:47.890001 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2025-11-11 00:13:47.893763 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.4 kB) 2025-11-11 00:13:48.189852 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2025-11-11 00:13:48.193866 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-11-11 00:13:48.211780 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2025-11-11 00:13:48.234358 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2025-11-11 00:13:48.254247 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2025-11-11 00:13:48.418588 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2025-11-11 00:13:48.422790 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2025-11-11 00:13:48.429434 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.9/site-packages (from cryptography->ansible-core==2.11.12.post0) (4.15.0) 2025-11-11 00:13:48.444089 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2025-11-11 00:13:48.448232 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2025-11-11 00:13:48.536020 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2025-11-11 00:13:48.540040 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.7 kB) 2025-11-11 00:13:48.552508 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2025-11-11 00:13:48.564168 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2025-11-11 00:13:48.628929 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 80.8 MB/s 0:00:00 2025-11-11 00:13:48.633343 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2025-11-11 00:13:48.644915 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-11-11 00:13:48.655977 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (20 kB) 2025-11-11 00:13:48.666391 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-11-11 00:13:48.680327 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (750 kB) 2025-11-11 00:13:48.703760 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 26.8 MB/s 0:00:00 2025-11-11 00:13:48.717106 | controller | Building wheels for collected packages: ansible-core 2025-11-11 00:13:48.717912 | controller | Building wheel for ansible-core (pyproject.toml): started 2025-11-11 00:13:50.115942 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2025-11-11 00:13:50.121176 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=cf027ffb092e5ea52248d79248cdde1e9584ecfe00df5d30019f7ed62b69ecaf 2025-11-11 00:13:50.121612 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-cysq68mz/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2025-11-11 00:13:50.133578 | controller | Successfully built ansible-core 2025-11-11 00:13:50.169603 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2025-11-11 00:13:51.603631 | controller | 2025-11-11 00:13:51.607443 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.11.12.post0 cffi-2.0.0 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23 resolvelib-0.5.4 2025-11-11 00:13:51.808085 | controller | ok: Runtime: 0:01:15.284498 2025-11-11 00:13:51.815201 | 2025-11-11 00:13:51.815288 | TASK [Setup download-artifact-fork role] 2025-11-11 00:13:51.835901 | controller | ok 2025-11-11 00:13:51.856861 | 2025-11-11 00:13:51.856969 | TASK [download-artifact-fork : Parse inventory response] 2025-11-11 00:13:51.889303 | controller | ok 2025-11-11 00:13:51.896953 | 2025-11-11 00:13:51.897075 | TASK [download-artifact-fork : Fetch info from Zuul API] 2025-11-11 00:13:51.921534 | controller | skipping: Conditional result was False 2025-11-11 00:13:51.929291 | 2025-11-11 00:13:51.929392 | TASK [download-artifact-fork : Parse build response] 2025-11-11 00:13:51.954527 | controller | skipping: Conditional result was False 2025-11-11 00:13:51.960956 | 2025-11-11 00:13:51.961028 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2025-11-11 00:13:52.172741 | controller | changed 2025-11-11 00:13:52.187141 | 2025-11-11 00:13:52.187318 | LOOP [download-artifact-fork : Download archive by type] 2025-11-11 00:13:52.217507 | controller | skipping: Conditional result was False 2025-11-11 00:13:52.217851 | 2025-11-11 00:13:52.928984 | controller | changed: OK (20201 bytes) 2025-11-11 00:13:52.946670 | 2025-11-11 00:13:52.946807 | TASK [deploy-artifacts : define __collections] 2025-11-11 00:13:52.977648 | controller | ok 2025-11-11 00:13:52.983511 | 2025-11-11 00:13:52.983598 | LOOP [deploy-artifacts : Create list of collections] 2025-11-11 00:13:53.009232 | controller | skipping: Conditional result was False 2025-11-11 00:13:53.026537 | controller | ok: 2025-11-11 00:13:53.026716 | controller | { 2025-11-11 00:13:53.026767 | controller | "branch": "main", 2025-11-11 00:13:53.027168 | controller | "job": "build-ansible-collection", 2025-11-11 00:13:53.027246 | controller | "metadata": { 2025-11-11 00:13:53.027289 | controller | "type": "ansible_collection", 2025-11-11 00:13:53.027328 | controller | "version": "5.0.0" 2025-11-11 00:13:53.027365 | controller | }, 2025-11-11 00:13:53.027400 | controller | "name": "ansible.network", 2025-11-11 00:13:53.027435 | controller | "project": "ansible-collections/ansible.network", 2025-11-11 00:13:53.027471 | controller | "url": "https://3ff6b3e0dd71d3cbde56-aa818fc55e7a73eefdd25368512e3d03.ssl.cf1.rackcdn.com/ansible/6a17a16b1ac14674a3d2d40bddb5327c/artifacts/ansible-network-5.0.0.tar.gz" 2025-11-11 00:13:53.027521 | controller | } 2025-11-11 00:13:53.038083 | 2025-11-11 00:13:53.038214 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2025-11-11 00:13:53.626423 | controller | Starting galaxy collection install process 2025-11-11 00:13:53.626498 | controller | Process install dependency map 2025-11-11 00:15:18.922666 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2025-11-11 00:15:18.923020 | controller | unexpected error when getting available versions of collection 2025-11-11 00:15:18.923052 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2025-11-11 00:15:18.923064 | controller | ansible/netcommon/versions/' 2025-11-11 00:15:18.923080 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2025-11-11 00:15:18.960171 | controller | to see the full traceback, use -vvv 2025-11-11 00:15:19.130354 | controller | ERROR 2025-11-11 00:15:19.130502 | controller | { 2025-11-11 00:15:19.130543 | controller | "delta": "0:01:25.710365", 2025-11-11 00:15:19.130570 | controller | "end": "2025-11-11 00:15:18.960734", 2025-11-11 00:15:19.130622 | controller | "msg": "non-zero return code", 2025-11-11 00:15:19.130648 | controller | "rc": 250, 2025-11-11 00:15:19.130672 | controller | "start": "2025-11-11 00:13:53.250369" 2025-11-11 00:15:19.130697 | controller | } failure 2025-11-11 00:15:19.132828 | 2025-11-11 00:15:19.132970 | PLAY RECAP 2025-11-11 00:15:19.133093 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2025-11-11 00:15:19.133161 | 2025-11-11 00:15:19.269452 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-11-11 00:15:19.270599 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-11 00:15:19.880059 | 2025-11-11 00:15:19.880184 | PLAY [all] 2025-11-11 00:15:19.902895 | 2025-11-11 00:15:19.903060 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-11-11 00:15:20.358202 | controller | changed: non-zero return code 2025-11-11 00:15:20.368117 | 2025-11-11 00:15:20.368279 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-11-11 00:15:20.393823 | controller | skipping: Conditional result was False 2025-11-11 00:15:20.400391 | 2025-11-11 00:15:20.400486 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-11-11 00:15:20.430892 | 2025-11-11 00:15:20.431079 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-11-11 00:15:20.460661 | 2025-11-11 00:15:20.460817 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-11-11 00:15:20.484513 | controller | skipping: Conditional result was False 2025-11-11 00:15:20.494421 | 2025-11-11 00:15:20.494557 | LOOP [fetch-subunit-output : Generate subunit file] 2025-11-11 00:15:20.527589 | 2025-11-11 00:15:20.527783 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-11-11 00:15:20.542651 | controller | skipping: Conditional result was False 2025-11-11 00:15:20.550156 | 2025-11-11 00:15:20.550294 | TASK [fetch-subunit-output : Remove the temporary file] 2025-11-11 00:15:20.564293 | controller | skipping: Conditional result was False 2025-11-11 00:15:20.571182 | 2025-11-11 00:15:20.571294 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-11-11 00:15:20.584978 | controller | skipping: Conditional result was False 2025-11-11 00:15:20.614748 | 2025-11-11 00:15:20.614843 | PLAY RECAP 2025-11-11 00:15:20.614882 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-11-11 00:15:20.614904 | 2025-11-11 00:15:20.720241 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-11-11 00:15:20.721192 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-11-11 00:15:21.322498 | 2025-11-11 00:15:21.322759 | PLAY [all:!appliance*] 2025-11-11 00:15:21.351251 | 2025-11-11 00:15:21.351400 | TASK [unregister the node] 2025-11-11 00:15:21.885658 | controller | skipping: Conditional result was False 2025-11-11 00:15:21.899454 | 2025-11-11 00:15:21.899646 | TASK [include_role : fetch-output] 2025-11-11 00:15:21.939653 | controller | ok 2025-11-11 00:15:21.987790 | 2025-11-11 00:15:21.987938 | TASK [fetch-output : Set log path for multiple nodes] 2025-11-11 00:15:22.063959 | controller | skipping: Conditional result was False 2025-11-11 00:15:22.073584 | 2025-11-11 00:15:22.073702 | TASK [fetch-output : Set log path for single node] 2025-11-11 00:15:22.124463 | controller | ok 2025-11-11 00:15:22.139269 | 2025-11-11 00:15:22.139596 | LOOP [fetch-output : Ensure local output dirs] 2025-11-11 00:15:22.573003 | controller -> localhost | ok: "/var/lib/zuul/builds/b20b897140484866b781f41ad9151dee/work/logs" 2025-11-11 00:15:22.797506 | controller -> localhost | changed: "/var/lib/zuul/builds/b20b897140484866b781f41ad9151dee/work/artifacts" 2025-11-11 00:15:23.023313 | controller -> localhost | changed: "/var/lib/zuul/builds/b20b897140484866b781f41ad9151dee/work/docs" 2025-11-11 00:15:23.033073 | 2025-11-11 00:15:23.033191 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-11 00:15:23.758603 | controller | changed: 2025-11-11 00:15:23.758932 | controller | .d..t...... ./ 2025-11-11 00:15:23.758978 | controller | cd+++++++++ controller/ 2025-11-11 00:15:23.759046 | controller | changed: All items complete 2025-11-11 00:15:23.759116 | 2025-11-11 00:15:24.264229 | controller | changed: .d..t...... ./ 2025-11-11 00:15:24.810290 | controller | changed: .d..t...... ./ 2025-11-11 00:15:24.831462 | 2025-11-11 00:15:24.831643 | TASK [include_role : fetch-output-openshift] 2025-11-11 00:15:24.845962 | controller | skipping: Conditional result was False 2025-11-11 00:15:24.853715 | 2025-11-11 00:15:24.853844 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-11-11 00:15:24.886200 | controller | skipping: Conditional result was False 2025-11-11 00:15:24.898288 | controller | skipping: Conditional result was False 2025-11-11 00:15:24.940645 | 2025-11-11 00:15:24.940770 | PLAY [localhost] 2025-11-11 00:15:24.955632 | 2025-11-11 00:15:24.955772 | TASK [Run Zuul manifest role] 2025-11-11 00:15:24.974149 | localhost | ok 2025-11-11 00:15:24.989985 | 2025-11-11 00:15:24.990128 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-11 00:15:25.375567 | localhost | changed 2025-11-11 00:15:25.380946 | 2025-11-11 00:15:25.381076 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-11 00:15:25.421694 | localhost | ok 2025-11-11 00:15:25.432706 | 2025-11-11 00:15:25.432835 | TASK [Set zuul-log-path fact] 2025-11-11 00:15:25.452730 | localhost | ok 2025-11-11 00:15:25.471773 | 2025-11-11 00:15:25.472035 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-11 00:15:25.505004 | localhost | ok 2025-11-11 00:15:25.516308 | 2025-11-11 00:15:25.516456 | LOOP [Run upload-logs-swift role] 2025-11-11 00:15:25.552367 | localhost | Output suppressed because no_log was given 2025-11-11 00:15:25.586970 | 2025-11-11 00:15:25.587110 | TASK [Set zuul-log-path fact] 2025-11-11 00:15:25.610940 | localhost | skipping: Conditional result was False 2025-11-11 00:15:25.616380 | 2025-11-11 00:15:25.616457 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-11-11 00:15:26.027279 | localhost -> localhost | ok: Runtime: 0:00:00.005936 2025-11-11 00:15:26.033613 | 2025-11-11 00:15:26.033697 | TASK [upload-logs-swift : Upload logs to swift]