2026-05-06 19:22:24.923162 | Job console starting 2026-05-06 19:22:24.940865 | Updating git repos 2026-05-06 19:22:24.982080 | Cloning repos into workspace 2026-05-06 19:22:25.106577 | Restoring repo states 2026-05-06 19:22:25.129865 | Merging changes 2026-05-06 19:22:26.184921 | Checking out repos 2026-05-06 19:22:26.297830 | Preparing playbooks 2026-05-06 19:22:27.984696 | Running Ansible setup 2026-05-06 19:22:33.479482 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-06 19:22:34.790807 | 2026-05-06 19:22:34.790950 | PLAY [Base pre] 2026-05-06 19:22:34.824073 | 2026-05-06 19:22:34.824189 | TASK [Setup log path fact] 2026-05-06 19:22:34.853427 | debian-bookworm | ok 2026-05-06 19:22:34.871546 | 2026-05-06 19:22:34.871662 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-06 19:22:34.908486 | debian-bookworm | ok 2026-05-06 19:22:34.921556 | 2026-05-06 19:22:34.921651 | TASK [emit-job-header : Print job information] 2026-05-06 19:22:34.958740 | # Job Information 2026-05-06 19:22:34.958888 | Ansible Version: 2.16.14 2026-05-06 19:22:34.958917 | Job: python-osism-unit-tests 2026-05-06 19:22:34.958943 | Pipeline: check 2026-05-06 19:22:34.958962 | Executor: 521e9411259a 2026-05-06 19:22:34.958979 | Triggered by: https://github.com/osism/python-osism/pull/2266 2026-05-06 19:22:34.958997 | Event ID: e1c9db20-4980-11f1-9497-ae231571a2b7 2026-05-06 19:22:34.963129 | 2026-05-06 19:22:34.963211 | LOOP [emit-job-header : Print node information] 2026-05-06 19:22:35.078428 | debian-bookworm | ok: 2026-05-06 19:22:35.078591 | debian-bookworm | # Node Information 2026-05-06 19:22:35.078651 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-06 19:22:35.078676 | debian-bookworm | Hostname: debian 2026-05-06 19:22:35.078695 | debian-bookworm | Username: zuul 2026-05-06 19:22:35.078712 | debian-bookworm | Distro: Debian 12.13 2026-05-06 19:22:35.078732 | debian-bookworm | Provider: regiocloud-a 2026-05-06 19:22:35.078749 | debian-bookworm | Region: 2026-05-06 19:22:35.078767 | debian-bookworm | Label: debian-bookworm 2026-05-06 19:22:35.078783 | debian-bookworm | Product Name: OpenStack Nova 2026-05-06 19:22:35.078799 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fed1:a41d 2026-05-06 19:22:35.105714 | 2026-05-06 19:22:35.105817 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-06 19:22:35.994556 | debian-bookworm -> localhost | changed 2026-05-06 19:22:36.000955 | 2026-05-06 19:22:36.001049 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-06 19:22:37.949921 | debian-bookworm -> localhost | changed 2026-05-06 19:22:37.958498 | 2026-05-06 19:22:37.958588 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-06 19:22:38.643225 | debian-bookworm -> localhost | ok 2026-05-06 19:22:38.653022 | 2026-05-06 19:22:38.653887 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-06 19:22:38.718746 | debian-bookworm | ok 2026-05-06 19:22:38.744330 | debian-bookworm | included: /var/lib/zuul/builds/dc9c7953a46f41c69c7c8cee5c8b2c94/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-06 19:22:38.761615 | 2026-05-06 19:22:38.761720 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-06 19:22:40.657674 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-06 19:22:40.657856 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/dc9c7953a46f41c69c7c8cee5c8b2c94/work/dc9c7953a46f41c69c7c8cee5c8b2c94_id_rsa 2026-05-06 19:22:40.657892 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/dc9c7953a46f41c69c7c8cee5c8b2c94/work/dc9c7953a46f41c69c7c8cee5c8b2c94_id_rsa.pub 2026-05-06 19:22:40.657918 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-06 19:22:40.657941 | debian-bookworm -> localhost | SHA256:adQaMbaKbvEdofvGJuU0mOPL7x7X9QJSMB1Mx8i/Hg4 zuul-build-sshkey 2026-05-06 19:22:40.657964 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-06 19:22:40.657993 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-06 19:22:40.658016 | debian-bookworm -> localhost | | + o=o+. | 2026-05-06 19:22:40.658037 | debian-bookworm -> localhost | | . = o=.. | 2026-05-06 19:22:40.658057 | debian-bookworm -> localhost | | = . .. | 2026-05-06 19:22:40.658077 | debian-bookworm -> localhost | | . + = . . | 2026-05-06 19:22:40.658097 | debian-bookworm -> localhost | | o ooS . . .. | 2026-05-06 19:22:40.658122 | debian-bookworm -> localhost | | . o+++. oEoo. | 2026-05-06 19:22:40.658144 | debian-bookworm -> localhost | | o.o*o.. .+...| 2026-05-06 19:22:40.658166 | debian-bookworm -> localhost | | . .o.=o o. | 2026-05-06 19:22:40.658187 | debian-bookworm -> localhost | | oO= | 2026-05-06 19:22:40.658208 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-06 19:22:40.658262 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.872886 2026-05-06 19:22:40.664519 | 2026-05-06 19:22:40.664618 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-06 19:22:40.697622 | debian-bookworm | ok 2026-05-06 19:22:40.708363 | debian-bookworm | included: /var/lib/zuul/builds/dc9c7953a46f41c69c7c8cee5c8b2c94/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-06 19:22:40.717449 | 2026-05-06 19:22:40.717550 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-06 19:22:40.731864 | debian-bookworm | skipping: Conditional result was False 2026-05-06 19:22:40.737867 | 2026-05-06 19:22:40.737956 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-06 19:22:41.455801 | debian-bookworm | changed 2026-05-06 19:22:41.468540 | 2026-05-06 19:22:41.468696 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-06 19:22:41.825728 | debian-bookworm | ok 2026-05-06 19:22:41.831273 | 2026-05-06 19:22:41.831366 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-06 19:22:42.495902 | debian-bookworm | changed 2026-05-06 19:22:42.502295 | 2026-05-06 19:22:42.502401 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-06 19:22:43.206670 | debian-bookworm | changed 2026-05-06 19:22:43.211439 | 2026-05-06 19:22:43.211527 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-06 19:22:43.249618 | debian-bookworm | skipping: Conditional result was False 2026-05-06 19:22:43.254540 | 2026-05-06 19:22:43.254626 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-06 19:22:44.110764 | debian-bookworm -> localhost | changed 2026-05-06 19:22:44.119458 | 2026-05-06 19:22:44.119549 | TASK [add-build-sshkey : Add back temp key] 2026-05-06 19:22:44.680312 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/dc9c7953a46f41c69c7c8cee5c8b2c94/work/dc9c7953a46f41c69c7c8cee5c8b2c94_id_rsa (zuul-build-sshkey) 2026-05-06 19:22:44.680507 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.036911 2026-05-06 19:22:44.687063 | 2026-05-06 19:22:44.687145 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-06 19:22:45.116661 | debian-bookworm | ok 2026-05-06 19:22:45.121718 | 2026-05-06 19:22:45.121896 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-06 19:22:45.145592 | debian-bookworm | skipping: Conditional result was False 2026-05-06 19:22:45.155542 | 2026-05-06 19:22:45.155637 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-06 19:22:45.566767 | debian-bookworm | ok 2026-05-06 19:22:45.582323 | 2026-05-06 19:22:45.589509 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-06 19:22:45.627849 | debian-bookworm | ok 2026-05-06 19:22:45.632811 | 2026-05-06 19:22:45.632909 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-06 19:22:46.161227 | debian-bookworm -> localhost | ok 2026-05-06 19:22:46.167568 | 2026-05-06 19:22:46.167667 | TASK [validate-host : Collect information about the host] 2026-05-06 19:22:47.410553 | debian-bookworm | ok 2026-05-06 19:22:47.428642 | 2026-05-06 19:22:47.428738 | TASK [validate-host : Sanitize hostname] 2026-05-06 19:22:47.615349 | debian-bookworm | ok 2026-05-06 19:22:47.719119 | 2026-05-06 19:22:47.719222 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-06 19:22:48.504205 | debian-bookworm -> localhost | changed 2026-05-06 19:22:48.508728 | 2026-05-06 19:22:48.508811 | TASK [validate-host : Collect information about zuul worker] 2026-05-06 19:22:49.092921 | debian-bookworm | ok 2026-05-06 19:22:49.097586 | 2026-05-06 19:22:49.097686 | TASK [validate-host : Write out all zuul information for each host] 2026-05-06 19:22:49.934994 | debian-bookworm -> localhost | changed 2026-05-06 19:22:49.949761 | 2026-05-06 19:22:49.949861 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-06 19:22:50.239765 | debian-bookworm | ok 2026-05-06 19:22:50.245101 | 2026-05-06 19:22:50.245187 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-06 19:22:51.363304 | debian-bookworm | changed: 2026-05-06 19:22:51.363489 | debian-bookworm | cd+++++++++ src/ 2026-05-06 19:22:51.363522 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-06 19:22:51.363546 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-06 19:22:51.363568 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-06 19:22:51.363589 | debian-bookworm | Containerfile 2026-05-06 19:22:51.363718 | debian-bookworm | Containerfile 2026-05-06 19:22:51.386567 | debian-bookworm | =2.14.2 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from ara==1.7.5->osism==0.20260502.1.dev13) (2.33.1) 2026-05-06 19:35:53.954222 | debian-bookworm | Collecting botocore<1.44.0,>=1.43.3 2026-05-06 19:35:53.967970 | debian-bookworm | Downloading botocore-1.43.4-py3-none-any.whl (15.0 MB) 2026-05-06 19:35:54.666668 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 15.0/15.0 MB 20.8 MB/s eta 0:00:00 2026-05-06 19:35:54.705900 | debian-bookworm | Requirement already satisfied: jmespath<2.0.0,>=0.7.1 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from boto3==1.43.3->osism==0.20260502.1.dev13) (1.1.0) 2026-05-06 19:35:54.758020 | debian-bookworm | Collecting s3transfer<0.18.0,>=0.17.0 2026-05-06 19:35:54.769918 | debian-bookworm | Downloading s3transfer-0.17.0-py3-none-any.whl (86 kB) 2026-05-06 19:35:54.779301 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 86.8/86.8 kB 12.3 MB/s eta 0:00:00 2026-05-06 19:35:54.847214 | debian-bookworm | Requirement already satisfied: billiard<5.0,>=4.2.1 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from celery[redis]==5.6.3->osism==0.20260502.1.dev13) (4.2.4) 2026-05-06 19:35:54.849143 | debian-bookworm | Requirement already satisfied: vine<6.0,>=5.1.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from celery[redis]==5.6.3->osism==0.20260502.1.dev13) (5.1.0) 2026-05-06 19:35:54.850519 | debian-bookworm | Requirement already satisfied: click<9.0,>=8.1.2 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from celery[redis]==5.6.3->osism==0.20260502.1.dev13) (8.3.3) 2026-05-06 19:35:54.852050 | debian-bookworm | Requirement already satisfied: click-didyoumean>=0.3.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from celery[redis]==5.6.3->osism==0.20260502.1.dev13) (0.3.1) 2026-05-06 19:35:54.853495 | debian-bookworm | Requirement already satisfied: click-repl>=0.2.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from celery[redis]==5.6.3->osism==0.20260502.1.dev13) (0.3.0) 2026-05-06 19:35:54.854930 | debian-bookworm | Requirement already satisfied: click-plugins>=1.1.1 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from celery[redis]==5.6.3->osism==0.20260502.1.dev13) (1.1.1.2) 2026-05-06 19:35:54.856619 | debian-bookworm | Requirement already satisfied: tzlocal in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from celery[redis]==5.6.3->osism==0.20260502.1.dev13) (5.3.1) 2026-05-06 19:35:54.867047 | debian-bookworm | Requirement already satisfied: autopage>=0.4.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from cliff==4.13.3->osism==0.20260502.1.dev13) (0.6.0) 2026-05-06 19:35:54.869065 | debian-bookworm | Requirement already satisfied: cmd2>=1.0.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from cliff==4.13.3->osism==0.20260502.1.dev13) (3.5.1) 2026-05-06 19:35:54.871330 | debian-bookworm | Requirement already satisfied: PrettyTable>=0.7.2 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from cliff==4.13.3->osism==0.20260502.1.dev13) (3.17.0) 2026-05-06 19:35:54.872650 | debian-bookworm | Requirement already satisfied: stevedore>=5.6.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from cliff==4.13.3->osism==0.20260502.1.dev13) (5.7.0) 2026-05-06 19:35:54.911819 | debian-bookworm | Requirement already satisfied: orderly-set<6,>=5.4.1 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from deepdiff==8.6.2->osism==0.20260502.1.dev13) (5.5.0) 2026-05-06 19:35:54.928821 | debian-bookworm | Requirement already satisfied: urllib3>=1.26.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from docker==7.1.0->osism==0.20260502.1.dev13) (2.6.3) 2026-05-06 19:35:54.935694 | debian-bookworm | Requirement already satisfied: polling in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from dtrack-auditor==1.5.0->osism==0.20260502.1.dev13) (0.3.2) 2026-05-06 19:35:54.936652 | debian-bookworm | Requirement already satisfied: certifi in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from dtrack-auditor==1.5.0->osism==0.20260502.1.dev13) (2026.4.22) 2026-05-06 19:35:55.061934 | debian-bookworm | Collecting starlette>=0.46.0 2026-05-06 19:35:55.073950 | debian-bookworm | Downloading starlette-1.0.0-py3-none-any.whl (72 kB) 2026-05-06 19:35:55.083693 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 72.7/72.7 kB 10.5 MB/s eta 0:00:00 2026-05-06 19:35:55.095496 | debian-bookworm | Requirement already satisfied: pydantic>=2.9.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from fastapi==0.136.1->osism==0.20260502.1.dev13) (2.13.4) 2026-05-06 19:35:55.097698 | debian-bookworm | Requirement already satisfied: typing-extensions>=4.8.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from fastapi==0.136.1->osism==0.20260502.1.dev13) (4.15.0) 2026-05-06 19:35:55.100773 | debian-bookworm | Requirement already satisfied: typing-inspection>=0.4.2 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from fastapi==0.136.1->osism==0.20260502.1.dev13) (0.4.2) 2026-05-06 19:35:55.130811 | debian-bookworm | Collecting annotated-doc>=0.0.2 2026-05-06 19:35:55.144710 | debian-bookworm | Downloading annotated_doc-0.0.4-py3-none-any.whl (5.3 kB) 2026-05-06 19:35:55.178724 | debian-bookworm | Requirement already satisfied: tornado<7.0.0,>=5.0.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from flower==2.0.1->osism==0.20260502.1.dev13) (6.5.5) 2026-05-06 19:35:55.180292 | debian-bookworm | Requirement already satisfied: prometheus-client>=0.8.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from flower==2.0.1->osism==0.20260502.1.dev13) (0.25.0) 2026-05-06 19:35:55.181548 | debian-bookworm | Requirement already satisfied: humanize in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from flower==2.0.1->osism==0.20260502.1.dev13) (4.15.0) 2026-05-06 19:35:55.183243 | debian-bookworm | Requirement already satisfied: pytz in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from flower==2.0.1->osism==0.20260502.1.dev13) (2026.2) 2026-05-06 19:35:55.206733 | debian-bookworm | Requirement already satisfied: gitdb<5,>=4.0.1 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from GitPython==3.1.49->osism==0.20260502.1.dev13) (4.0.12) 2026-05-06 19:35:55.224238 | debian-bookworm | Requirement already satisfied: ruamel.yaml>=0.15.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from jc==1.25.6->osism==0.20260502.1.dev13) (0.19.1) 2026-05-06 19:35:55.225955 | debian-bookworm | Requirement already satisfied: xmltodict>=0.12.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from jc==1.25.6->osism==0.20260502.1.dev13) (0.12.0) 2026-05-06 19:35:55.227329 | debian-bookworm | Requirement already satisfied: Pygments>=2.3.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from jc==1.25.6->osism==0.20260502.1.dev13) (2.20.0) 2026-05-06 19:35:55.237812 | debian-bookworm | Requirement already satisfied: MarkupSafe>=2.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from Jinja2==3.1.6->osism==0.20260502.1.dev13) (3.0.3) 2026-05-06 19:35:55.255778 | debian-bookworm | Requirement already satisfied: pbr>=2.0.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from keystoneauth1==5.13.1->osism==0.20260502.1.dev13) (7.0.3) 2026-05-06 19:35:55.257130 | debian-bookworm | Requirement already satisfied: iso8601>=2.0.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from keystoneauth1==5.13.1->osism==0.20260502.1.dev13) (2.1.0) 2026-05-06 19:35:55.259160 | debian-bookworm | Requirement already satisfied: os-service-types>=1.2.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from keystoneauth1==5.13.1->osism==0.20260502.1.dev13) (1.8.2) 2026-05-06 19:35:55.294307 | debian-bookworm | Requirement already satisfied: amqp<6.0.0,>=5.1.1 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from kombu==5.6.2->osism==0.20260502.1.dev13) (5.3.1) 2026-05-06 19:35:55.295945 | debian-bookworm | Requirement already satisfied: tzdata>=2025.2 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from kombu==5.6.2->osism==0.20260502.1.dev13) (2026.2) 2026-05-06 19:35:55.296921 | debian-bookworm | Requirement already satisfied: packaging in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from kombu==5.6.2->osism==0.20260502.1.dev13) (26.2) 2026-05-06 19:35:55.316291 | debian-bookworm | Requirement already satisfied: six>=1.9.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from kubernetes==33.1.0->osism==0.20260502.1.dev13) (1.17.0) 2026-05-06 19:35:55.319072 | debian-bookworm | Requirement already satisfied: google-auth>=1.0.1 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from kubernetes==33.1.0->osism==0.20260502.1.dev13) (2.50.0) 2026-05-06 19:35:55.321447 | debian-bookworm | Requirement already satisfied: websocket-client!=0.40.0,!=0.41.*,!=0.42.*,>=0.32.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from kubernetes==33.1.0->osism==0.20260502.1.dev13) (1.9.0) 2026-05-06 19:35:55.323452 | debian-bookworm | Requirement already satisfied: requests-oauthlib in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from kubernetes==33.1.0->osism==0.20260502.1.dev13) (2.0.0) 2026-05-06 19:35:55.325058 | debian-bookworm | Requirement already satisfied: oauthlib>=3.2.2 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from kubernetes==33.1.0->osism==0.20260502.1.dev13) (3.3.1) 2026-05-06 19:35:55.326767 | debian-bookworm | Requirement already satisfied: durationpy>=0.7 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from kubernetes==33.1.0->osism==0.20260502.1.dev13) (0.10) 2026-05-06 19:35:55.422390 | debian-bookworm | Requirement already satisfied: cryptography>=2.7 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from openstacksdk==4.10.0->osism==0.20260502.1.dev13) (48.0.0) 2026-05-06 19:35:55.423713 | debian-bookworm | Requirement already satisfied: decorator>=4.4.1 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from openstacksdk==4.10.0->osism==0.20260502.1.dev13) (5.2.1) 2026-05-06 19:35:55.425391 | debian-bookworm | Requirement already satisfied: dogpile.cache>=0.6.5 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from openstacksdk==4.10.0->osism==0.20260502.1.dev13) (1.5.0) 2026-05-06 19:35:55.427528 | debian-bookworm | Requirement already satisfied: jsonpatch!=1.20,>=1.16 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from openstacksdk==4.10.0->osism==0.20260502.1.dev13) (1.33) 2026-05-06 19:35:55.429887 | debian-bookworm | Requirement already satisfied: platformdirs>=3 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from openstacksdk==4.10.0->osism==0.20260502.1.dev13) (4.9.6) 2026-05-06 19:35:55.432322 | debian-bookworm | Requirement already satisfied: psutil>=3.2.2 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from openstacksdk==4.10.0->osism==0.20260502.1.dev13) (7.2.2) 2026-05-06 19:35:55.433904 | debian-bookworm | Requirement already satisfied: requestsexceptions>=1.2.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from openstacksdk==4.10.0->osism==0.20260502.1.dev13) (1.4.0) 2026-05-06 19:35:55.452704 | debian-bookworm | Requirement already satisfied: bcrypt>=3.2 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from paramiko==4.0.0->osism==0.20260502.1.dev13) (5.0.0) 2026-05-06 19:35:55.454686 | debian-bookworm | Requirement already satisfied: invoke>=2.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from paramiko==4.0.0->osism==0.20260502.1.dev13) (3.0.3) 2026-05-06 19:35:55.456251 | debian-bookworm | Requirement already satisfied: pynacl>=1.5 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from paramiko==4.0.0->osism==0.20260502.1.dev13) (1.6.2) 2026-05-06 19:35:55.470973 | debian-bookworm | Requirement already satisfied: redis>=4.2.0rc1 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from pottery==3.0.1->osism==0.20260502.1.dev13) (6.4.0) 2026-05-06 19:35:55.472431 | debian-bookworm | Requirement already satisfied: mmh3 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from pottery==3.0.1->osism==0.20260502.1.dev13) (5.2.1) 2026-05-06 19:35:55.488043 | debian-bookworm | Requirement already satisfied: wcwidth in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from prompt-toolkit==3.0.52->osism==0.20260502.1.dev13) (0.7.0) 2026-05-06 19:35:55.561551 | debian-bookworm | Requirement already satisfied: pytest>=6 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from pytest-testinfra==10.2.2->osism==0.20260502.1.dev13) (9.0.3) 2026-05-06 19:35:55.615418 | debian-bookworm | Requirement already satisfied: jsonpath_ng in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from redfish==3.3.5->osism==0.20260502.1.dev13) (1.8.0) 2026-05-06 19:35:55.616386 | debian-bookworm | Requirement already satisfied: jsonpointer in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from redfish==3.3.5->osism==0.20260502.1.dev13) (3.1.1) 2026-05-06 19:35:55.618733 | debian-bookworm | Requirement already satisfied: requests_toolbelt in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from redfish==3.3.5->osism==0.20260502.1.dev13) (1.0.0) 2026-05-06 19:35:55.619677 | debian-bookworm | Requirement already satisfied: requests-unixsocket in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from redfish==3.3.5->osism==0.20260502.1.dev13) (0.4.1) 2026-05-06 19:35:55.640976 | debian-bookworm | Requirement already satisfied: SQLAlchemy<2.1.0,>=2.0.14 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from sqlmodel==0.0.38->osism==0.20260502.1.dev13) (2.0.49) 2026-05-06 19:35:55.784587 | debian-bookworm | Collecting h11>=0.8 2026-05-06 19:35:55.797532 | debian-bookworm | Downloading h11-0.16.0-py3-none-any.whl (37 kB) 2026-05-06 19:35:55.904821 | debian-bookworm | Collecting httptools>=0.6.3 2026-05-06 19:35:55.919700 | debian-bookworm | Downloading httptools-0.7.1-cp311-cp311-manylinux1_x86_64.manylinux_2_28_x86_64.manylinux_2_5_x86_64.whl (456 kB) 2026-05-06 19:35:55.951515 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 456.6/456.6 kB 18.1 MB/s eta 0:00:00 2026-05-06 19:35:56.012305 | debian-bookworm | Collecting python-dotenv>=0.13 2026-05-06 19:35:56.025031 | debian-bookworm | Downloading python_dotenv-1.2.2-py3-none-any.whl (22 kB) 2026-05-06 19:35:56.150793 | debian-bookworm | Collecting uvloop>=0.15.1 2026-05-06 19:35:56.165147 | debian-bookworm | Downloading uvloop-0.22.1-cp311-cp311-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (3.8 MB) 2026-05-06 19:35:56.343224 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 3.8/3.8 MB 21.7 MB/s eta 0:00:00 2026-05-06 19:35:56.600214 | debian-bookworm | Collecting watchfiles>=0.20 2026-05-06 19:35:56.612645 | debian-bookworm | Downloading watchfiles-1.1.1-cp311-cp311-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (456 kB) 2026-05-06 19:35:56.643135 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 456.1/456.1 kB 18.5 MB/s eta 0:00:00 2026-05-06 19:35:57.253251 | debian-bookworm | Requirement already satisfied: pyperclip>=1.8.2 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from cmd2>=1.0.0->cliff==4.13.3->osism==0.20260502.1.dev13) (1.11.0) 2026-05-06 19:35:57.255207 | debian-bookworm | Requirement already satisfied: rich>=15.0.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from cmd2>=1.0.0->cliff==4.13.3->osism==0.20260502.1.dev13) (15.0.0) 2026-05-06 19:35:57.256871 | debian-bookworm | Requirement already satisfied: rich-argparse>=1.7.2 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from cmd2>=1.0.0->cliff==4.13.3->osism==0.20260502.1.dev13) (1.8.0) 2026-05-06 19:35:57.294487 | debian-bookworm | Requirement already satisfied: cffi>=2.0.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from cryptography>=2.7->openstacksdk==4.10.0->osism==0.20260502.1.dev13) (2.0.0) 2026-05-06 19:35:57.436146 | debian-bookworm | Requirement already satisfied: smmap<6,>=3.0.1 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from gitdb<5,>=4.0.1->GitPython==3.1.49->osism==0.20260502.1.dev13) (5.0.3) 2026-05-06 19:35:57.495741 | debian-bookworm | Requirement already satisfied: pyasn1-modules>=0.2.1 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from google-auth>=1.0.1->kubernetes==33.1.0->osism==0.20260502.1.dev13) (0.4.2) 2026-05-06 19:35:58.188899 | debian-bookworm | Requirement already satisfied: annotated-types>=0.6.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from pydantic>=2.9.0->fastapi==0.136.1->osism==0.20260502.1.dev13) (0.7.0) 2026-05-06 19:35:58.190722 | debian-bookworm | Requirement already satisfied: pydantic-core==2.46.4 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from pydantic>=2.9.0->fastapi==0.136.1->osism==0.20260502.1.dev13) (2.46.4) 2026-05-06 19:35:58.392605 | debian-bookworm | Requirement already satisfied: iniconfig>=1.0.1 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from pytest>=6->pytest-testinfra==10.2.2->osism==0.20260502.1.dev13) (2.3.0) 2026-05-06 19:35:58.394365 | debian-bookworm | Requirement already satisfied: pluggy<2,>=1.5 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from pytest>=6->pytest-testinfra==10.2.2->osism==0.20260502.1.dev13) (1.6.0) 2026-05-06 19:35:58.538864 | debian-bookworm | Requirement already satisfied: async-timeout>=4.0.3 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from redis>=4.2.0rc1->pottery==3.0.1->osism==0.20260502.1.dev13) (5.0.1) 2026-05-06 19:35:58.592296 | debian-bookworm | Requirement already satisfied: charset_normalizer<4,>=2 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from requests>=2.14.2->ara==1.7.5->osism==0.20260502.1.dev13) (3.4.7) 2026-05-06 19:35:58.594091 | debian-bookworm | Requirement already satisfied: idna<4,>=2.5 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from requests>=2.14.2->ara==1.7.5->osism==0.20260502.1.dev13) (3.13) 2026-05-06 19:35:59.016343 | debian-bookworm | Requirement already satisfied: greenlet>=1 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from SQLAlchemy<2.1.0,>=2.0.14->sqlmodel==0.0.38->osism==0.20260502.1.dev13) (3.5.0) 2026-05-06 19:35:59.125965 | debian-bookworm | Collecting anyio<5,>=3.6.2 2026-05-06 19:35:59.137915 | debian-bookworm | Downloading anyio-4.13.0-py3-none-any.whl (114 kB) 2026-05-06 19:35:59.147896 | debian-bookworm | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 114.4/114.4 kB 16.0 MB/s eta 0:00:00 2026-05-06 19:36:00.764478 | debian-bookworm | Requirement already satisfied: pycparser in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from cffi>=2.0.0->cryptography>=2.7->openstacksdk==4.10.0->osism==0.20260502.1.dev13) (3.0) 2026-05-06 19:36:01.181714 | debian-bookworm | Requirement already satisfied: pyasn1<0.7.0,>=0.6.1 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from pyasn1-modules>=0.2.1->google-auth>=1.0.1->kubernetes==33.1.0->osism==0.20260502.1.dev13) (0.6.3) 2026-05-06 19:36:01.325701 | debian-bookworm | Requirement already satisfied: markdown-it-py>=2.2.0 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from rich>=15.0.0->cmd2>=1.0.0->cliff==4.13.3->osism==0.20260502.1.dev13) (4.1.0) 2026-05-06 19:36:01.576760 | debian-bookworm | Requirement already satisfied: mdurl~=0.1 in /home/zuul/.local/share/virtualenvs/python-osism-A84SJ9It/lib/python3.11/site-packages (from markdown-it-py>=2.2.0->rich>=15.0.0->cmd2>=1.0.0->cliff==4.13.3->osism==0.20260502.1.dev13) (0.1.2) 2026-05-06 19:36:01.980760 | debian-bookworm | Building wheels for collected packages: osism 2026-05-06 19:36:01.981474 | debian-bookworm | Building wheel for osism (setup.py): started 2026-05-06 19:36:02.966120 | debian-bookworm | Building wheel for osism (setup.py): finished with status 'done' 2026-05-06 19:36:02.968066 | debian-bookworm | Created wheel for osism: filename=osism-0.20260502.1.dev13-py3-none-any.whl size=258418 sha256=62e3891962d8a1fb4d339735b879a1974a7956f77b23a4e454bd0973c4f76b64 2026-05-06 19:36:02.968643 | debian-bookworm | Stored in directory: /home/zuul/.cache/pip/wheels/a1/f3/73/4a66715db3e61cd6101f4a742f32969d09906101b6738a3792 2026-05-06 19:36:02.975860 | debian-bookworm | Successfully built osism 2026-05-06 19:36:03.833353 | debian-bookworm | Installing collected packages: websockets, uvloop, python-dotenv, httptools, h11, anyio, annotated-doc, watchfiles, uvicorn, starlette, botocore, s3transfer, fastapi, boto3, osism 2026-05-06 19:36:05.903887 | debian-bookworm | Successfully installed annotated-doc-0.0.4 anyio-4.13.0 boto3-1.43.3 botocore-1.43.4 fastapi-0.136.1 h11-0.16.0 httptools-0.7.1 osism-0.20260502.1.dev13 python-dotenv-1.2.2 s3transfer-0.17.0 starlette-1.0.0 uvicorn-0.42.0 uvloop-0.22.1 watchfiles-1.1.1 websockets-16.0 2026-05-06 19:36:06.716031 | debian-bookworm | ok: Runtime: 0:12:05.733936 2026-05-06 19:36:06.731017 | 2026-05-06 19:36:06.731194 | TASK [Run pytest] 2026-05-06 19:36:07.060377 | debian-bookworm | + export PATH=/usr/local/bin:/usr/bin:/bin:/usr/games:/home/zuul/.local/bin 2026-05-06 19:36:07.060550 | debian-bookworm | + PATH=/usr/local/bin:/usr/bin:/bin:/usr/games:/home/zuul/.local/bin 2026-05-06 19:36:07.060571 | debian-bookworm | + pipenv run pytest 2026-05-06 19:36:10.704418 | debian-bookworm | ============================= test session starts ============================== 2026-05-06 19:36:10.704562 | debian-bookworm | platform linux -- Python 3.11.2, pytest-9.0.3, pluggy-1.6.0 2026-05-06 19:36:10.704590 | debian-bookworm | rootdir: /home/zuul/src/github.com/osism/python-osism 2026-05-06 19:36:10.704611 | debian-bookworm | configfile: setup.cfg 2026-05-06 19:36:10.704656 | debian-bookworm | testpaths: tests/unit 2026-05-06 19:36:10.704683 | debian-bookworm | plugins: cov-6.0.0, anyio-4.13.0, mock-3.14.0, testinfra-10.2.2 2026-05-06 19:36:10.704698 | debian-bookworm | collected 708 items 2026-05-06 19:36:10.704710 | debian-bookworm | 2026-05-06 19:36:10.717337 | debian-bookworm | tests/unit/commands/test_manage_validators.py ............... [ 2%] 2026-05-06 19:36:10.768947 | debian-bookworm | tests/unit/commands/test_manage_wiring.py .... [ 2%] 2026-05-06 19:36:11.012517 | debian-bookworm | tests/unit/commands/test_sonic_ssh.py .....................FFFFF........ [ 7%] 2026-05-06 19:36:11.035729 | debian-bookworm | ...... [ 8%] 2026-05-06 19:36:11.061835 | debian-bookworm | tests/unit/data/test_enums.py ................................. [ 12%] 2026-05-06 19:36:11.092805 | debian-bookworm | tests/unit/data/test_playbooks.py ............ [ 14%] 2026-05-06 19:36:11.121382 | debian-bookworm | tests/unit/tasks/conductor/sonic/test_bgp.py ........................... [ 18%] 2026-05-06 19:36:11.127243 | debian-bookworm | .... [ 19%] 2026-05-06 19:36:11.167189 | debian-bookworm | tests/unit/tasks/conductor/sonic/test_breakout_detection.py ............ [ 20%] 2026-05-06 19:36:11.212726 | debian-bookworm | ................ [ 23%] 2026-05-06 19:36:11.284936 | debian-bookworm | tests/unit/tasks/conductor/sonic/test_cache.py ......................... [ 26%] 2026-05-06 19:36:11.292734 | debian-bookworm | ... [ 26%] 2026-05-06 19:36:11.299721 | debian-bookworm | tests/unit/tasks/conductor/sonic/test_config_generator_dns.py ... [ 27%] 2026-05-06 19:36:11.306849 | debian-bookworm | tests/unit/tasks/conductor/sonic/test_config_generator_log.py ..... [ 28%] 2026-05-06 19:36:11.310493 | debian-bookworm | tests/unit/tasks/conductor/sonic/test_config_generator_metalbox_cache.py . [ 28%] 2026-05-06 19:36:11.342798 | debian-bookworm | ........... [ 29%] 2026-05-06 19:36:11.349736 | debian-bookworm | tests/unit/tasks/conductor/sonic/test_config_generator_ntp.py ... [ 30%] 2026-05-06 19:36:11.350806 | debian-bookworm | tests/unit/tasks/conductor/sonic/test_config_generator_orchestrator.py . [ 30%] 2026-05-06 19:36:11.913686 | debian-bookworm | ............................ [ 34%] 2026-05-06 19:36:11.940549 | debian-bookworm | tests/unit/tasks/conductor/sonic/test_config_generator_snmp.py ......... [ 35%] 2026-05-06 19:36:11.941374 | debian-bookworm | [ 35%] 2026-05-06 19:36:11.969598 | debian-bookworm | tests/unit/tasks/conductor/sonic/test_connections.py ................... [ 38%] 2026-05-06 19:36:12.056148 | debian-bookworm | .................................. [ 43%] 2026-05-06 19:36:12.072143 | debian-bookworm | tests/unit/tasks/conductor/sonic/test_constants.py ..................... [ 46%] 2026-05-06 19:36:12.078943 | debian-bookworm | ........ [ 47%] 2026-05-06 19:36:12.106716 | debian-bookworm | tests/unit/tasks/conductor/sonic/test_device.py ........................ [ 50%] 2026-05-06 19:36:12.121324 | debian-bookworm | ..... [ 51%] 2026-05-06 19:36:12.139777 | debian-bookworm | tests/unit/tasks/conductor/sonic/test_interface_conversion.py .......... [ 52%] 2026-05-06 19:36:12.300796 | debian-bookworm | ............................................................. [ 61%] 2026-05-06 19:36:12.322659 | debian-bookworm | tests/unit/tasks/conductor/sonic/test_port_channel_detection.py ........ [ 62%] 2026-05-06 19:36:12.354969 | debian-bookworm | ............. [ 64%] 2026-05-06 19:36:12.363543 | debian-bookworm | tests/unit/tasks/conductor/sonic/test_validator.py .......... [ 65%] 2026-05-06 19:36:12.592600 | debian-bookworm | tests/unit/tasks/conductor/test_config.py .............................. [ 69%] 2026-05-06 19:36:12.646123 | debian-bookworm | ....... [ 70%] 2026-05-06 19:36:12.815783 | debian-bookworm | tests/unit/tasks/conductor/test_netbox.py .............................. [ 75%] 2026-05-06 19:36:12.985475 | debian-bookworm | ............. [ 76%] 2026-05-06 19:36:13.026291 | debian-bookworm | tests/unit/tasks/conductor/test_utils.py ............................... [ 81%] 2026-05-06 19:36:13.068127 | debian-bookworm | ........................... [ 85%] 2026-05-06 19:36:13.185524 | debian-bookworm | tests/unit/test_settings.py ............................................ [ 91%] 2026-05-06 19:36:13.263040 | debian-bookworm | ............................ [ 95%] 2026-05-06 19:36:13.265625 | debian-bookworm | tests/unit/test_smoke.py .. [ 95%] 2026-05-06 19:36:13.352282 | debian-bookworm | tests/unit/utils/test_http.py ............... [ 97%] 2026-05-06 19:36:13.625597 | debian-bookworm | tests/unit/utils/test_inventory.py ................ [100%] 2026-05-06 19:36:13.625674 | debian-bookworm | 2026-05-06 19:36:13.625691 | debian-bookworm | =================================== FAILURES =================================== 2026-05-06 19:36:13.625703 | debian-bookworm | __________ test_create_ssh_connection_calls_remove_when_refresh_true ___________ 2026-05-06 19:36:13.625715 | debian-bookworm | 2026-05-06 19:36:13.625728 | debian-bookworm | _exists = 2026-05-06 19:36:13.625748 | debian-bookworm | _ensure = 2026-05-06 19:36:13.625775 | debian-bookworm | mock_remove = 2026-05-06 19:36:13.625799 | debian-bookworm | _paramiko = 2026-05-06 19:36:13.625818 | debian-bookworm | 2026-05-06 19:36:13.625837 | debian-bookworm | @patch("osism.commands.sonic.paramiko") 2026-05-06 19:36:13.625863 | debian-bookworm | @patch("osism.commands.sonic.remove_known_hosts_entries") 2026-05-06 19:36:13.625879 | debian-bookworm | @patch("osism.commands.sonic.ensure_known_hosts_file", return_value=True) 2026-05-06 19:36:13.625890 | debian-bookworm | @patch("osism.commands.sonic.os.path.exists", return_value=True) 2026-05-06 19:36:13.625901 | debian-bookworm | def test_create_ssh_connection_calls_remove_when_refresh_true( 2026-05-06 19:36:13.625913 | debian-bookworm | _exists, _ensure, mock_remove, _paramiko 2026-05-06 19:36:13.625924 | debian-bookworm | ): 2026-05-06 19:36:13.625936 | debian-bookworm | > base = _make_base() 2026-05-06 19:36:13.625949 | debian-bookworm | ^^^^^^^^^^^^ 2026-05-06 19:36:13.625960 | debian-bookworm | 2026-05-06 19:36:13.625972 | debian-bookworm | tests/unit/commands/test_sonic_ssh.py:82: 2026-05-06 19:36:13.625983 | debian-bookworm | _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 2026-05-06 19:36:13.625994 | debian-bookworm | 2026-05-06 19:36:13.626031 | debian-bookworm | def _make_base(): 2026-05-06 19:36:13.626044 | debian-bookworm | > return sonic.SonicCommandBase(MagicMock(), MagicMock()) 2026-05-06 19:36:13.626056 | debian-bookworm | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-05-06 19:36:13.626068 | debian-bookworm | E TypeError: Can't instantiate abstract class SonicCommandBase with abstract method take_action 2026-05-06 19:36:13.626080 | debian-bookworm | 2026-05-06 19:36:13.626091 | debian-bookworm | tests/unit/commands/test_sonic_ssh.py:72: TypeError 2026-05-06 19:36:13.626103 | debian-bookworm | __________ test_create_ssh_connection_skips_remove_when_refresh_false __________ 2026-05-06 19:36:13.626114 | debian-bookworm | 2026-05-06 19:36:13.626125 | debian-bookworm | _exists = 2026-05-06 19:36:13.626136 | debian-bookworm | _ensure = 2026-05-06 19:36:13.626148 | debian-bookworm | mock_remove = 2026-05-06 19:36:13.626159 | debian-bookworm | _paramiko = 2026-05-06 19:36:13.626170 | debian-bookworm | 2026-05-06 19:36:13.626181 | debian-bookworm | @patch("osism.commands.sonic.paramiko") 2026-05-06 19:36:13.626216 | debian-bookworm | @patch("osism.commands.sonic.remove_known_hosts_entries") 2026-05-06 19:36:13.626228 | debian-bookworm | @patch("osism.commands.sonic.ensure_known_hosts_file", return_value=True) 2026-05-06 19:36:13.626240 | debian-bookworm | @patch("osism.commands.sonic.os.path.exists", return_value=True) 2026-05-06 19:36:13.626251 | debian-bookworm | def test_create_ssh_connection_skips_remove_when_refresh_false( 2026-05-06 19:36:13.626262 | debian-bookworm | _exists, _ensure, mock_remove, _paramiko 2026-05-06 19:36:13.626274 | debian-bookworm | ): 2026-05-06 19:36:13.626286 | debian-bookworm | > base = _make_base() 2026-05-06 19:36:13.626297 | debian-bookworm | ^^^^^^^^^^^^ 2026-05-06 19:36:13.626308 | debian-bookworm | 2026-05-06 19:36:13.626321 | debian-bookworm | tests/unit/commands/test_sonic_ssh.py:95: 2026-05-06 19:36:13.626332 | debian-bookworm | _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 2026-05-06 19:36:13.626344 | debian-bookworm | 2026-05-06 19:36:13.626355 | debian-bookworm | def _make_base(): 2026-05-06 19:36:13.626366 | debian-bookworm | > return sonic.SonicCommandBase(MagicMock(), MagicMock()) 2026-05-06 19:36:13.626378 | debian-bookworm | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-05-06 19:36:13.626389 | debian-bookworm | E TypeError: Can't instantiate abstract class SonicCommandBase with abstract method take_action 2026-05-06 19:36:13.626401 | debian-bookworm | 2026-05-06 19:36:13.626412 | debian-bookworm | tests/unit/commands/test_sonic_ssh.py:72: TypeError 2026-05-06 19:36:13.626423 | debian-bookworm | _____________ test_create_ssh_connection_default_does_not_refresh ______________ 2026-05-06 19:36:13.626435 | debian-bookworm | 2026-05-06 19:36:13.626446 | debian-bookworm | _exists = 2026-05-06 19:36:13.626458 | debian-bookworm | _ensure = 2026-05-06 19:36:13.626469 | debian-bookworm | mock_remove = 2026-05-06 19:36:13.626480 | debian-bookworm | _paramiko = 2026-05-06 19:36:13.626492 | debian-bookworm | 2026-05-06 19:36:13.626503 | debian-bookworm | @patch("osism.commands.sonic.paramiko") 2026-05-06 19:36:13.626514 | debian-bookworm | @patch("osism.commands.sonic.remove_known_hosts_entries") 2026-05-06 19:36:13.626525 | debian-bookworm | @patch("osism.commands.sonic.ensure_known_hosts_file", return_value=True) 2026-05-06 19:36:13.626537 | debian-bookworm | @patch("osism.commands.sonic.os.path.exists", return_value=True) 2026-05-06 19:36:13.626548 | debian-bookworm | def test_create_ssh_connection_default_does_not_refresh( 2026-05-06 19:36:13.626559 | debian-bookworm | _exists, _ensure, mock_remove, _paramiko 2026-05-06 19:36:13.626571 | debian-bookworm | ): 2026-05-06 19:36:13.626582 | debian-bookworm | """Default value preserves prior (non-refreshing) behavior.""" 2026-05-06 19:36:13.626593 | debian-bookworm | > base = _make_base() 2026-05-06 19:36:13.626605 | debian-bookworm | ^^^^^^^^^^^^ 2026-05-06 19:36:13.626616 | debian-bookworm | 2026-05-06 19:36:13.626627 | debian-bookworm | tests/unit/commands/test_sonic_ssh.py:108: 2026-05-06 19:36:13.626640 | debian-bookworm | _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 2026-05-06 19:36:13.626651 | debian-bookworm | 2026-05-06 19:36:13.626663 | debian-bookworm | def _make_base(): 2026-05-06 19:36:13.626674 | debian-bookworm | > return sonic.SonicCommandBase(MagicMock(), MagicMock()) 2026-05-06 19:36:13.626686 | debian-bookworm | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-05-06 19:36:13.626697 | debian-bookworm | E TypeError: Can't instantiate abstract class SonicCommandBase with abstract method take_action 2026-05-06 19:36:13.626709 | debian-bookworm | 2026-05-06 19:36:13.626720 | debian-bookworm | tests/unit/commands/test_sonic_ssh.py:72: TypeError 2026-05-06 19:36:13.626732 | debian-bookworm | ___________ test_create_ssh_connection_returns_none_when_key_missing ___________ 2026-05-06 19:36:13.626743 | debian-bookworm | 2026-05-06 19:36:13.626754 | debian-bookworm | _exists = 2026-05-06 19:36:13.626766 | debian-bookworm | _ensure = 2026-05-06 19:36:13.626796 | debian-bookworm | mock_remove = 2026-05-06 19:36:13.626808 | debian-bookworm | _paramiko = 2026-05-06 19:36:13.626820 | debian-bookworm | 2026-05-06 19:36:13.626831 | debian-bookworm | @patch("osism.commands.sonic.paramiko") 2026-05-06 19:36:13.626843 | debian-bookworm | @patch("osism.commands.sonic.remove_known_hosts_entries") 2026-05-06 19:36:13.626862 | debian-bookworm | @patch("osism.commands.sonic.ensure_known_hosts_file", return_value=True) 2026-05-06 19:36:13.626873 | debian-bookworm | @patch("osism.commands.sonic.os.path.exists", return_value=False) 2026-05-06 19:36:13.626897 | debian-bookworm | def test_create_ssh_connection_returns_none_when_key_missing( 2026-05-06 19:36:13.626909 | debian-bookworm | _exists, _ensure, mock_remove, _paramiko 2026-05-06 19:36:13.626921 | debian-bookworm | ): 2026-05-06 19:36:13.626932 | debian-bookworm | """Missing private key short-circuits before any host-key handling.""" 2026-05-06 19:36:13.626944 | debian-bookworm | > base = _make_base() 2026-05-06 19:36:13.626956 | debian-bookworm | ^^^^^^^^^^^^ 2026-05-06 19:36:13.626967 | debian-bookworm | 2026-05-06 19:36:13.626979 | debian-bookworm | tests/unit/commands/test_sonic_ssh.py:121: 2026-05-06 19:36:13.626990 | debian-bookworm | _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 2026-05-06 19:36:13.627001 | debian-bookworm | 2026-05-06 19:36:13.627030 | debian-bookworm | def _make_base(): 2026-05-06 19:36:13.627042 | debian-bookworm | > return sonic.SonicCommandBase(MagicMock(), MagicMock()) 2026-05-06 19:36:13.627054 | debian-bookworm | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-05-06 19:36:13.627066 | debian-bookworm | E TypeError: Can't instantiate abstract class SonicCommandBase with abstract method take_action 2026-05-06 19:36:13.627077 | debian-bookworm | 2026-05-06 19:36:13.627088 | debian-bookworm | tests/unit/commands/test_sonic_ssh.py:72: TypeError 2026-05-06 19:36:13.627099 | debian-bookworm | ___________ test_create_ssh_connection_continues_when_refresh_fails ____________ 2026-05-06 19:36:13.627110 | debian-bookworm | 2026-05-06 19:36:13.627122 | debian-bookworm | _exists = 2026-05-06 19:36:13.627133 | debian-bookworm | _ensure = 2026-05-06 19:36:13.627144 | debian-bookworm | _mock_remove = 2026-05-06 19:36:13.627156 | debian-bookworm | mock_paramiko = 2026-05-06 19:36:13.627167 | debian-bookworm | 2026-05-06 19:36:13.627178 | debian-bookworm | @patch("osism.commands.sonic.paramiko") 2026-05-06 19:36:13.627190 | debian-bookworm | @patch( 2026-05-06 19:36:13.627202 | debian-bookworm | "osism.commands.sonic.remove_known_hosts_entries", 2026-05-06 19:36:13.627213 | debian-bookworm | side_effect=PermissionError("denied"), 2026-05-06 19:36:13.627225 | debian-bookworm | ) 2026-05-06 19:36:13.627240 | debian-bookworm | @patch("osism.commands.sonic.ensure_known_hosts_file", return_value=True) 2026-05-06 19:36:13.627263 | debian-bookworm | @patch("osism.commands.sonic.os.path.exists", return_value=True) 2026-05-06 19:36:13.627293 | debian-bookworm | def test_create_ssh_connection_continues_when_refresh_fails( 2026-05-06 19:36:13.627314 | debian-bookworm | _exists, _ensure, _mock_remove, mock_paramiko 2026-05-06 19:36:13.627335 | debian-bookworm | ): 2026-05-06 19:36:13.627355 | debian-bookworm | """If the host-key refresh raises, we log and proceed with the connection.""" 2026-05-06 19:36:13.627375 | debian-bookworm | > base = _make_base() 2026-05-06 19:36:13.627396 | debian-bookworm | ^^^^^^^^^^^^ 2026-05-06 19:36:13.627417 | debian-bookworm | 2026-05-06 19:36:13.627437 | debian-bookworm | tests/unit/commands/test_sonic_ssh.py:138: 2026-05-06 19:36:13.627456 | debian-bookworm | _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ _ 2026-05-06 19:36:13.627477 | debian-bookworm | 2026-05-06 19:36:13.627497 | debian-bookworm | def _make_base(): 2026-05-06 19:36:13.627517 | debian-bookworm | > return sonic.SonicCommandBase(MagicMock(), MagicMock()) 2026-05-06 19:36:13.627537 | debian-bookworm | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2026-05-06 19:36:13.627557 | debian-bookworm | E TypeError: Can't instantiate abstract class SonicCommandBase with abstract method take_action 2026-05-06 19:36:13.627578 | debian-bookworm | 2026-05-06 19:36:13.627599 | debian-bookworm | tests/unit/commands/test_sonic_ssh.py:72: TypeError 2026-05-06 19:36:13.627620 | debian-bookworm | =========================== short test summary info ============================ 2026-05-06 19:36:13.627641 | debian-bookworm | FAILED tests/unit/commands/test_sonic_ssh.py::test_create_ssh_connection_calls_remove_when_refresh_true 2026-05-06 19:36:13.627670 | debian-bookworm | FAILED tests/unit/commands/test_sonic_ssh.py::test_create_ssh_connection_skips_remove_when_refresh_false 2026-05-06 19:36:13.627694 | debian-bookworm | FAILED tests/unit/commands/test_sonic_ssh.py::test_create_ssh_connection_default_does_not_refresh 2026-05-06 19:36:13.627728 | debian-bookworm | FAILED tests/unit/commands/test_sonic_ssh.py::test_create_ssh_connection_returns_none_when_key_missing 2026-05-06 19:36:13.627750 | debian-bookworm | FAILED tests/unit/commands/test_sonic_ssh.py::test_create_ssh_connection_continues_when_refresh_fails 2026-05-06 19:36:13.627773 | debian-bookworm | ======================== 5 failed, 703 passed in 5.05s ========================= 2026-05-06 19:36:14.269313 | debian-bookworm | ERROR 2026-05-06 19:36:14.269686 | debian-bookworm | { 2026-05-06 19:36:14.269754 | debian-bookworm | "delta": "0:00:06.795860", 2026-05-06 19:36:14.269795 | debian-bookworm | "end": "2026-05-06 19:36:13.847358", 2026-05-06 19:36:14.269829 | debian-bookworm | "msg": "non-zero return code", 2026-05-06 19:36:14.269861 | debian-bookworm | "rc": 1, 2026-05-06 19:36:14.269891 | debian-bookworm | "start": "2026-05-06 19:36:07.051498" 2026-05-06 19:36:14.269925 | debian-bookworm | } failure 2026-05-06 19:36:14.274796 | 2026-05-06 19:36:14.274942 | PLAY RECAP 2026-05-06 19:36:14.275024 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-05-06 19:36:14.275064 | 2026-05-06 19:36:14.451900 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/python-osism/playbooks/test-unit.yml@main] 2026-05-06 19:36:14.452866 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-06 19:36:15.239416 | 2026-05-06 19:36:15.239624 | PLAY [Base post-fetch] 2026-05-06 19:36:15.254755 | 2026-05-06 19:36:15.254918 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-06 19:36:15.310363 | debian-bookworm | skipping: Conditional result was False 2026-05-06 19:36:15.322206 | 2026-05-06 19:36:15.322420 | TASK [fetch-output : Set log path for single node] 2026-05-06 19:36:15.379127 | debian-bookworm | ok 2026-05-06 19:36:15.387921 | 2026-05-06 19:36:15.388097 | LOOP [fetch-output : Ensure local output dirs] 2026-05-06 19:36:15.876595 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/dc9c7953a46f41c69c7c8cee5c8b2c94/work/logs" 2026-05-06 19:36:16.170423 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/dc9c7953a46f41c69c7c8cee5c8b2c94/work/artifacts" 2026-05-06 19:36:16.477665 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/dc9c7953a46f41c69c7c8cee5c8b2c94/work/docs" 2026-05-06 19:36:16.493355 | 2026-05-06 19:36:16.493508 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-06 19:36:17.312491 | debian-bookworm | changed: 2026-05-06 19:36:17.312843 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-06 19:36:17.312891 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-06 19:36:17.312941 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-06 19:36:17.313011 | debian-bookworm | changed: All items complete 2026-05-06 19:36:17.313043 | 2026-05-06 19:36:17.899294 | debian-bookworm | changed: .d..t...... ./ 2026-05-06 19:36:18.463754 | debian-bookworm | changed: .d..t...... ./ 2026-05-06 19:36:18.488414 | 2026-05-06 19:36:18.488670 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-06 19:36:19.019702 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.009356 2026-05-06 19:36:19.332166 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.010277 2026-05-06 19:36:19.345956 | 2026-05-06 19:36:19.346048 | PLAY RECAP 2026-05-06 19:36:19.346100 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-06 19:36:19.346126 | 2026-05-06 19:36:19.474869 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-06 19:36:19.477107 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-06 19:36:20.210826 | 2026-05-06 19:36:20.211038 | PLAY [Base post] 2026-05-06 19:36:20.225129 | 2026-05-06 19:36:20.225269 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-06 19:36:21.054129 | debian-bookworm | changed 2026-05-06 19:36:21.062869 | 2026-05-06 19:36:21.063012 | PLAY RECAP 2026-05-06 19:36:21.063095 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-06 19:36:21.063175 | 2026-05-06 19:36:21.202956 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-06 19:36:21.204648 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-06 19:36:22.010501 | 2026-05-06 19:36:22.010727 | PLAY [Base post-logs] 2026-05-06 19:36:22.022031 | 2026-05-06 19:36:22.022192 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-06 19:36:22.495063 | localhost | changed 2026-05-06 19:36:22.505263 | 2026-05-06 19:36:22.505464 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-06 19:36:22.544144 | localhost | ok 2026-05-06 19:36:22.551044 | 2026-05-06 19:36:22.551220 | TASK [Set zuul-log-path fact] 2026-05-06 19:36:22.579905 | localhost | ok 2026-05-06 19:36:22.594349 | 2026-05-06 19:36:22.594557 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-06 19:36:22.631753 | localhost | ok 2026-05-06 19:36:22.636976 | 2026-05-06 19:36:22.637151 | TASK [upload-logs : Create log directories] 2026-05-06 19:36:23.126696 | localhost | changed 2026-05-06 19:36:23.131731 | 2026-05-06 19:36:23.131888 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-06 19:36:23.646118 | localhost -> localhost | ok: Runtime: 0:00:00.007142 2026-05-06 19:36:23.655145 | 2026-05-06 19:36:23.655336 | TASK [upload-logs : Upload logs to log server] 2026-05-06 19:36:24.243157 | localhost | Output suppressed because no_log was given 2026-05-06 19:36:24.247422 | 2026-05-06 19:36:24.247702 | LOOP [upload-logs : Compress console log and json output] 2026-05-06 19:36:24.309119 | localhost | skipping: Conditional result was False 2026-05-06 19:36:24.314612 | localhost | skipping: Conditional result was False 2026-05-06 19:36:24.327808 | 2026-05-06 19:36:24.327945 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-06 19:36:24.385336 | localhost | skipping: Conditional result was False 2026-05-06 19:36:24.386004 | 2026-05-06 19:36:24.389362 | localhost | skipping: Conditional result was False 2026-05-06 19:36:24.403417 | 2026-05-06 19:36:24.403742 | LOOP [upload-logs : Upload console log and json output]