2026-06-27 17:30:29.200419 | Job console starting 2026-06-27 17:30:29.257758 | Updating git repos 2026-06-27 17:30:29.333532 | Cloning repos into workspace 2026-06-27 17:30:29.409648 | Restoring repo states 2026-06-27 17:30:29.428547 | Merging changes 2026-06-27 17:30:30.083962 | Checking out repos 2026-06-27 17:30:30.132936 | Preparing playbooks 2026-06-27 17:30:31.145622 | Running Ansible setup 2026-06-27 17:30:35.783187 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-06-27 17:30:37.479485 | 2026-06-27 17:30:37.479671 | PLAY [Base pre] 2026-06-27 17:30:37.522630 | 2026-06-27 17:30:37.522806 | TASK [Setup log path fact] 2026-06-27 17:30:37.572990 | debian-bookworm | ok 2026-06-27 17:30:37.593185 | 2026-06-27 17:30:37.593362 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-27 17:30:37.642913 | debian-bookworm | ok 2026-06-27 17:30:37.660537 | 2026-06-27 17:30:37.660686 | TASK [emit-job-header : Print job information] 2026-06-27 17:30:37.725152 | # Job Information 2026-06-27 17:30:37.725346 | Ansible Version: 2.16.14 2026-06-27 17:30:37.725383 | Job: netbox-manager-unit-tests 2026-06-27 17:30:37.725415 | Pipeline: check 2026-06-27 17:30:37.725438 | Executor: 521e9411259a 2026-06-27 17:30:37.725459 | Triggered by: https://github.com/osism/netbox-manager/pull/265 2026-06-27 17:30:37.725482 | Event ID: dd151270-724d-11f1-90ed-a323f86dbe67 2026-06-27 17:30:37.734989 | 2026-06-27 17:30:37.735178 | LOOP [emit-job-header : Print node information] 2026-06-27 17:30:38.027625 | debian-bookworm | ok: 2026-06-27 17:30:38.030665 | debian-bookworm | # Node Information 2026-06-27 17:30:38.030765 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-06-27 17:30:38.030797 | debian-bookworm | Hostname: debian 2026-06-27 17:30:38.030821 | debian-bookworm | Username: zuul 2026-06-27 17:30:38.030870 | debian-bookworm | Distro: Debian 12.14 2026-06-27 17:30:38.030895 | debian-bookworm | Provider: regiocloud-a 2026-06-27 17:30:38.030917 | debian-bookworm | Region: 2026-06-27 17:30:38.030939 | debian-bookworm | Label: debian-bookworm 2026-06-27 17:30:38.030965 | debian-bookworm | Product Name: OpenStack Nova 2026-06-27 17:30:38.031197 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe3a:b125 2026-06-27 17:30:38.049534 | 2026-06-27 17:30:38.049680 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-27 17:30:38.882651 | debian-bookworm -> localhost | changed 2026-06-27 17:30:38.890279 | 2026-06-27 17:30:38.890424 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-27 17:30:40.643091 | debian-bookworm -> localhost | changed 2026-06-27 17:30:40.679330 | 2026-06-27 17:30:40.680953 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-27 17:30:41.195250 | debian-bookworm -> localhost | ok 2026-06-27 17:30:41.202387 | 2026-06-27 17:30:41.202534 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-27 17:30:41.241510 | debian-bookworm | ok 2026-06-27 17:30:41.279422 | debian-bookworm | included: /var/lib/zuul/builds/8acbe5bea2f94c7b83b3078691f4e4a5/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-27 17:30:41.291322 | 2026-06-27 17:30:41.291465 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-27 17:30:42.711890 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-06-27 17:30:42.712155 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/8acbe5bea2f94c7b83b3078691f4e4a5/work/8acbe5bea2f94c7b83b3078691f4e4a5_id_rsa 2026-06-27 17:30:42.712197 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/8acbe5bea2f94c7b83b3078691f4e4a5/work/8acbe5bea2f94c7b83b3078691f4e4a5_id_rsa.pub 2026-06-27 17:30:42.712224 | debian-bookworm -> localhost | The key fingerprint is: 2026-06-27 17:30:42.712251 | debian-bookworm -> localhost | SHA256:26VY5/EWrUY9OxapeqRm+dCmDqwJbDDLrjVGEdRattI zuul-build-sshkey 2026-06-27 17:30:42.712275 | debian-bookworm -> localhost | The key's randomart image is: 2026-06-27 17:30:42.712312 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-06-27 17:30:42.712335 | debian-bookworm -> localhost | | .o. | 2026-06-27 17:30:42.712358 | debian-bookworm -> localhost | | .+ | 2026-06-27 17:30:42.712378 | debian-bookworm -> localhost | | .= . | 2026-06-27 17:30:42.712399 | debian-bookworm -> localhost | | o.E o.| 2026-06-27 17:30:42.712419 | debian-bookworm -> localhost | | +. S . + o+o| 2026-06-27 17:30:42.712446 | debian-bookworm -> localhost | | o = .= =.=.o+| 2026-06-27 17:30:42.712467 | debian-bookworm -> localhost | | * + ooo.=+=+ | 2026-06-27 17:30:42.712487 | debian-bookworm -> localhost | | + o . o .==+. .| 2026-06-27 17:30:42.712507 | debian-bookworm -> localhost | | ... o ++o. | 2026-06-27 17:30:42.712527 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-06-27 17:30:42.712582 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.589764 2026-06-27 17:30:42.720449 | 2026-06-27 17:30:42.720588 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-27 17:30:42.768860 | debian-bookworm | ok 2026-06-27 17:30:42.787951 | debian-bookworm | included: /var/lib/zuul/builds/8acbe5bea2f94c7b83b3078691f4e4a5/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-27 17:30:42.812326 | 2026-06-27 17:30:42.812486 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-27 17:30:42.849684 | debian-bookworm | skipping: Conditional result was False 2026-06-27 17:30:42.856354 | 2026-06-27 17:30:42.856482 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-27 17:30:43.527187 | debian-bookworm | changed 2026-06-27 17:30:43.536613 | 2026-06-27 17:30:43.536775 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-27 17:30:43.835404 | debian-bookworm | ok 2026-06-27 17:30:43.843530 | 2026-06-27 17:30:43.843692 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-27 17:30:44.515885 | debian-bookworm | changed 2026-06-27 17:30:44.529715 | 2026-06-27 17:30:44.529910 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-27 17:30:45.241323 | debian-bookworm | changed 2026-06-27 17:30:45.252913 | 2026-06-27 17:30:45.253230 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-27 17:30:45.281013 | debian-bookworm | skipping: Conditional result was False 2026-06-27 17:30:45.288512 | 2026-06-27 17:30:45.288636 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-27 17:30:46.141070 | debian-bookworm -> localhost | changed 2026-06-27 17:30:46.159322 | 2026-06-27 17:30:46.159482 | TASK [add-build-sshkey : Add back temp key] 2026-06-27 17:30:46.713737 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/8acbe5bea2f94c7b83b3078691f4e4a5/work/8acbe5bea2f94c7b83b3078691f4e4a5_id_rsa (zuul-build-sshkey) 2026-06-27 17:30:46.714305 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.015817 2026-06-27 17:30:46.725871 | 2026-06-27 17:30:46.725991 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-27 17:30:47.307933 | debian-bookworm | ok 2026-06-27 17:30:47.347065 | 2026-06-27 17:30:47.348103 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-27 17:30:47.383783 | debian-bookworm | skipping: Conditional result was False 2026-06-27 17:30:47.411862 | 2026-06-27 17:30:47.412063 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-27 17:30:47.982820 | debian-bookworm | ok 2026-06-27 17:30:47.998073 | 2026-06-27 17:30:47.998219 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-27 17:30:48.028779 | debian-bookworm | ok 2026-06-27 17:30:48.036960 | 2026-06-27 17:30:48.037140 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-27 17:30:48.865971 | debian-bookworm -> localhost | ok 2026-06-27 17:30:48.880141 | 2026-06-27 17:30:48.880290 | TASK [validate-host : Collect information about the host] 2026-06-27 17:30:50.307651 | debian-bookworm | ok 2026-06-27 17:30:50.327256 | 2026-06-27 17:30:50.327420 | TASK [validate-host : Sanitize hostname] 2026-06-27 17:30:50.625770 | debian-bookworm | ok 2026-06-27 17:30:50.640521 | 2026-06-27 17:30:50.640670 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-27 17:30:51.757970 | debian-bookworm -> localhost | changed 2026-06-27 17:30:51.764494 | 2026-06-27 17:30:51.764620 | TASK [validate-host : Collect information about zuul worker] 2026-06-27 17:30:52.199130 | debian-bookworm | ok 2026-06-27 17:30:52.209070 | 2026-06-27 17:30:52.209225 | TASK [validate-host : Write out all zuul information for each host] 2026-06-27 17:30:53.188331 | debian-bookworm -> localhost | changed 2026-06-27 17:30:53.205665 | 2026-06-27 17:30:53.205806 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-06-27 17:30:53.482350 | debian-bookworm | ok 2026-06-27 17:30:53.506965 | 2026-06-27 17:30:53.507180 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-06-27 17:30:54.715360 | debian-bookworm | changed: 2026-06-27 17:30:54.715582 | debian-bookworm | cd+++++++++ src/ 2026-06-27 17:30:54.715672 | debian-bookworm | cd+++++++++ src/github.com/ 2026-06-27 17:30:54.715704 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-06-27 17:30:54.715771 | debian-bookworm | cd+++++++++ src/github.com/osism/netbox-manager/ 2026-06-27 17:30:54.715794 | debian-bookworm | netbox_manager/requirements.yml 2026-06-27 17:30:54.716107 | debian-bookworm | cL+++++++++ src/github.com/osism/netbox-manager/settings.toml.sample -> netbox_manager/settings.toml.sample 2026-06-27 17:30:54.716126 | debian-bookworm | 2026-06-27 17:31:34.417665 | debian-bookworm | [DeployException]: sys.exit(cli()) 2026-06-27 17:31:34.417673 | debian-bookworm | [DeployException]: ^^^^^ 2026-06-27 17:31:34.417678 | debian-bookworm | [DeployException]: File 2026-06-27 17:31:34.417682 | debian-bookworm | "/tmp/venv/lib/python3.11/site-packages/pipenv/cli/command.py", line 801, in cli 2026-06-27 17:31:34.417686 | debian-bookworm | [DeployException]: handler(args, state) 2026-06-27 17:31:34.417691 | debian-bookworm | [DeployException]: File 2026-06-27 17:31:34.417695 | debian-bookworm | "/tmp/venv/lib/python3.11/site-packages/pipenv/cli/command.py", line 70, in 2026-06-27 17:31:34.417699 | debian-bookworm | cmd_install 2026-06-27 17:31:34.417703 | debian-bookworm | [DeployException]: do_install( 2026-06-27 17:31:34.417707 | debian-bookworm | [DeployException]: File 2026-06-27 17:31:34.417711 | debian-bookworm | "/tmp/venv/lib/python3.11/site-packages/pipenv/routines/install.py", line 415, 2026-06-27 17:31:34.417717 | debian-bookworm | in do_install 2026-06-27 17:31:34.417721 | debian-bookworm | [DeployException]: do_init( 2026-06-27 17:31:34.417725 | debian-bookworm | [DeployException]: File 2026-06-27 17:31:34.417729 | debian-bookworm | "/tmp/venv/lib/python3.11/site-packages/pipenv/routines/install.py", line 927, 2026-06-27 17:31:34.417732 | debian-bookworm | in do_init 2026-06-27 17:31:34.417737 | debian-bookworm | [DeployException]: handle_lockfile( 2026-06-27 17:31:34.417740 | debian-bookworm | [DeployException]: File 2026-06-27 17:31:34.417744 | debian-bookworm | "/tmp/venv/lib/python3.11/site-packages/pipenv/routines/install.py", line 270, 2026-06-27 17:31:34.417748 | debian-bookworm | in handle_lockfile 2026-06-27 17:31:34.417752 | debian-bookworm | [DeployException]: raise exceptions.DeployException 2026-06-27 17:31:34.418311 | debian-bookworm | ERROR:: Aborting deploy 2026-06-27 17:31:34.951997 | debian-bookworm | ERROR 2026-06-27 17:31:34.952254 | debian-bookworm | { 2026-06-27 17:31:34.952294 | debian-bookworm | "delta": "0:00:01.691105", 2026-06-27 17:31:34.952319 | debian-bookworm | "end": "2026-06-27 17:31:34.605053", 2026-06-27 17:31:34.952340 | debian-bookworm | "msg": "non-zero return code", 2026-06-27 17:31:34.952360 | debian-bookworm | "rc": 1, 2026-06-27 17:31:34.952379 | debian-bookworm | "start": "2026-06-27 17:31:32.913948" 2026-06-27 17:31:34.952397 | debian-bookworm | } failure 2026-06-27 17:31:34.954319 | 2026-06-27 17:31:34.954414 | PLAY RECAP 2026-06-27 17:31:34.954465 | debian-bookworm | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-06-27 17:31:34.954488 | 2026-06-27 17:31:35.183217 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/netbox-manager/playbooks/test-unit.yml@main] 2026-06-27 17:31:35.191160 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-06-27 17:31:36.067571 | 2026-06-27 17:31:36.067769 | PLAY [Base post-fetch] 2026-06-27 17:31:36.083841 | 2026-06-27 17:31:36.084065 | TASK [diagnose-network : Ensure log directory exists] 2026-06-27 17:31:36.523434 | debian-bookworm | ok 2026-06-27 17:31:36.535673 | 2026-06-27 17:31:36.535935 | TASK [diagnose-network : Snapshot dual-stack network state (post)] 2026-06-27 17:31:48.371359 | debian-bookworm | ok: Runtime: 0:00:06.071372 2026-06-27 17:31:48.380650 | 2026-06-27 17:31:48.380828 | TASK [diagnose-network : Snapshot Docker/BuildKit IPv6 config (teardown)] 2026-06-27 17:31:54.426139 | debian-bookworm | ok: Runtime: 0:00:00.009294 2026-06-27 17:31:54.433660 | 2026-06-27 17:31:54.433813 | TASK [diagnose-network : Probe container network namespace (teardown)] 2026-06-27 17:32:00.457537 | debian-bookworm | ok: Runtime: 0:00:00.007113 2026-06-27 17:32:00.474882 | 2026-06-27 17:32:00.475086 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-27 17:32:00.529843 | debian-bookworm | skipping: Conditional result was False 2026-06-27 17:32:00.540579 | 2026-06-27 17:32:00.540764 | TASK [fetch-output : Set log path for single node] 2026-06-27 17:32:00.592613 | debian-bookworm | ok 2026-06-27 17:32:00.603618 | 2026-06-27 17:32:00.603796 | LOOP [fetch-output : Ensure local output dirs] 2026-06-27 17:32:00.917990 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/8acbe5bea2f94c7b83b3078691f4e4a5/work/logs" 2026-06-27 17:32:01.237996 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/8acbe5bea2f94c7b83b3078691f4e4a5/work/artifacts" 2026-06-27 17:32:01.632389 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/8acbe5bea2f94c7b83b3078691f4e4a5/work/docs" 2026-06-27 17:32:01.652430 | 2026-06-27 17:32:01.652600 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-27 17:32:02.661910 | debian-bookworm | changed: 2026-06-27 17:32:02.662181 | debian-bookworm | .d..t...... ./ 2026-06-27 17:32:02.662230 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-06-27 17:32:02.662255 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-06-27 17:32:02.662277 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-06-27 17:32:02.662298 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-06-27 17:32:02.662319 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-06-27 17:32:02.662360 | debian-bookworm | changed: All items complete 2026-06-27 17:32:02.662385 | 2026-06-27 17:32:03.238119 | debian-bookworm | changed: .d..t...... ./ 2026-06-27 17:32:03.818245 | debian-bookworm | changed: .d..t...... ./ 2026-06-27 17:32:03.843267 | 2026-06-27 17:32:03.843528 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-27 17:32:04.212385 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.005363 2026-06-27 17:32:04.576972 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.009993 2026-06-27 17:32:04.585638 | 2026-06-27 17:32:04.585746 | PLAY RECAP 2026-06-27 17:32:04.585798 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-27 17:32:04.585823 | 2026-06-27 17:32:04.749441 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-06-27 17:32:04.750349 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-06-27 17:32:05.563079 | 2026-06-27 17:32:05.563285 | PLAY [Base post] 2026-06-27 17:32:05.584180 | 2026-06-27 17:32:05.584359 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-27 17:32:06.183873 | debian-bookworm | changed 2026-06-27 17:32:06.196126 | 2026-06-27 17:32:06.196272 | PLAY RECAP 2026-06-27 17:32:06.196340 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-27 17:32:06.196405 | 2026-06-27 17:32:06.400037 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-06-27 17:32:06.401473 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-27 17:32:07.270544 | 2026-06-27 17:32:07.270733 | PLAY [Base post-logs] 2026-06-27 17:32:07.281884 | 2026-06-27 17:32:07.282048 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-27 17:32:07.788162 | localhost | changed 2026-06-27 17:32:07.799021 | 2026-06-27 17:32:07.799193 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-27 17:32:07.839394 | localhost | ok 2026-06-27 17:32:07.843252 | 2026-06-27 17:32:07.843380 | TASK [Set zuul-log-path fact] 2026-06-27 17:32:07.860101 | localhost | ok 2026-06-27 17:32:07.869184 | 2026-06-27 17:32:07.869314 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-27 17:32:07.895223 | localhost | ok 2026-06-27 17:32:07.899285 | 2026-06-27 17:32:07.899429 | TASK [upload-logs : Create log directories] 2026-06-27 17:32:08.399169 | localhost | changed 2026-06-27 17:32:08.403975 | 2026-06-27 17:32:08.404137 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-27 17:32:08.931377 | localhost -> localhost | ok: Runtime: 0:00:00.006899 2026-06-27 17:32:08.940812 | 2026-06-27 17:32:08.941053 | TASK [upload-logs : Upload logs to log server] 2026-06-27 17:32:09.563369 | localhost | Output suppressed because no_log was given 2026-06-27 17:32:09.566994 | 2026-06-27 17:32:09.567221 | LOOP [upload-logs : Compress console log and json output] 2026-06-27 17:32:09.625486 | localhost | skipping: Conditional result was False 2026-06-27 17:32:09.631755 | localhost | skipping: Conditional result was False 2026-06-27 17:32:09.639906 | 2026-06-27 17:32:09.640050 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-27 17:32:09.697533 | localhost | skipping: Conditional result was False 2026-06-27 17:32:09.698470 | 2026-06-27 17:32:09.701380 | localhost | skipping: Conditional result was False 2026-06-27 17:32:09.716174 | 2026-06-27 17:32:09.716456 | LOOP [upload-logs : Upload console log and json output]