2026-05-03 14:53:12.667669 | Job console starting 2026-05-03 14:53:12.682511 | Updating git repos 2026-05-03 14:53:12.729924 | Cloning repos into workspace 2026-05-03 14:53:12.803058 | Restoring repo states 2026-05-03 14:53:12.812736 | Merging changes 2026-05-03 14:53:13.393312 | Checking out repos 2026-05-03 14:53:13.433613 | Preparing playbooks 2026-05-03 14:53:14.114435 | Running Ansible setup 2026-05-03 14:53:18.575781 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-03 14:53:19.399572 | 2026-05-03 14:53:19.399797 | PLAY [Base pre] 2026-05-03 14:53:19.418767 | 2026-05-03 14:53:19.418984 | TASK [Setup log path fact] 2026-05-03 14:53:19.449805 | debian-bookworm | ok 2026-05-03 14:53:19.468271 | 2026-05-03 14:53:19.468536 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-03 14:53:19.511958 | debian-bookworm | ok 2026-05-03 14:53:19.524700 | 2026-05-03 14:53:19.524859 | TASK [emit-job-header : Print job information] 2026-05-03 14:53:19.583096 | # Job Information 2026-05-03 14:53:19.583516 | Ansible Version: 2.16.14 2026-05-03 14:53:19.583595 | Job: netbox-manager-unit-tests 2026-05-03 14:53:19.583664 | Pipeline: check 2026-05-03 14:53:19.583713 | Executor: 521e9411259a 2026-05-03 14:53:19.583756 | Triggered by: https://github.com/osism/netbox-manager/pull/234 2026-05-03 14:53:19.583804 | Event ID: 79132a60-46ff-11f1-827a-5fd26847f886 2026-05-03 14:53:19.592629 | 2026-05-03 14:53:19.592828 | LOOP [emit-job-header : Print node information] 2026-05-03 14:53:19.713807 | debian-bookworm | ok: 2026-05-03 14:53:19.714175 | debian-bookworm | # Node Information 2026-05-03 14:53:19.714252 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-03 14:53:19.714308 | debian-bookworm | Hostname: debian 2026-05-03 14:53:19.714541 | debian-bookworm | Username: zuul 2026-05-03 14:53:19.714593 | debian-bookworm | Distro: Debian 12.13 2026-05-03 14:53:19.714635 | debian-bookworm | Provider: regiocloud-a 2026-05-03 14:53:19.714670 | debian-bookworm | Region: 2026-05-03 14:53:19.714707 | debian-bookworm | Label: debian-bookworm 2026-05-03 14:53:19.714740 | debian-bookworm | Product Name: OpenStack Nova 2026-05-03 14:53:19.714772 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe8d:81c8 2026-05-03 14:53:19.745145 | 2026-05-03 14:53:19.745422 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-03 14:53:20.299077 | debian-bookworm -> localhost | changed 2026-05-03 14:53:20.307106 | 2026-05-03 14:53:20.307250 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-03 14:53:21.445832 | debian-bookworm -> localhost | changed 2026-05-03 14:53:21.470199 | 2026-05-03 14:53:21.470458 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-03 14:53:21.792560 | debian-bookworm -> localhost | ok 2026-05-03 14:53:21.807497 | 2026-05-03 14:53:21.807740 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-03 14:53:21.851011 | debian-bookworm | ok 2026-05-03 14:53:21.872148 | debian-bookworm | included: /var/lib/zuul/builds/665ff386ce894da39a2d9266d1923a27/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-03 14:53:21.880913 | 2026-05-03 14:53:21.881076 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-03 14:53:23.025835 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-03 14:53:23.026548 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/665ff386ce894da39a2d9266d1923a27/work/665ff386ce894da39a2d9266d1923a27_id_rsa 2026-05-03 14:53:23.026624 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/665ff386ce894da39a2d9266d1923a27/work/665ff386ce894da39a2d9266d1923a27_id_rsa.pub 2026-05-03 14:53:23.026662 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-03 14:53:23.026695 | debian-bookworm -> localhost | SHA256:uyTV1Kz5E/I+i+4/mgp6Eo+X5XJ4y0rw0MUbymMgRF8 zuul-build-sshkey 2026-05-03 14:53:23.026730 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-03 14:53:23.026785 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-03 14:53:23.026817 | debian-bookworm -> localhost | | .o E | 2026-05-03 14:53:23.026875 | debian-bookworm -> localhost | | . . . . o | 2026-05-03 14:53:23.026909 | debian-bookworm -> localhost | | . o + . o | 2026-05-03 14:53:23.026941 | debian-bookworm -> localhost | | . + o = o | 2026-05-03 14:53:23.026971 | debian-bookworm -> localhost | | o * S = . | 2026-05-03 14:53:23.027008 | debian-bookworm -> localhost | | .= o.. + . | 2026-05-03 14:53:23.027043 | debian-bookworm -> localhost | | +==o + | 2026-05-03 14:53:23.027074 | debian-bookworm -> localhost | | ooB=+. +o. | 2026-05-03 14:53:23.027105 | debian-bookworm -> localhost | | .+.===*o++ | 2026-05-03 14:53:23.027135 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-03 14:53:23.027231 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.619623 2026-05-03 14:53:23.038796 | 2026-05-03 14:53:23.039063 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-03 14:53:23.076840 | debian-bookworm | ok 2026-05-03 14:53:23.093657 | debian-bookworm | included: /var/lib/zuul/builds/665ff386ce894da39a2d9266d1923a27/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-03 14:53:23.105721 | 2026-05-03 14:53:23.105954 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-03 14:53:23.131010 | debian-bookworm | skipping: Conditional result was False 2026-05-03 14:53:23.138530 | 2026-05-03 14:53:23.138745 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-03 14:53:23.827244 | debian-bookworm | changed 2026-05-03 14:53:23.836063 | 2026-05-03 14:53:23.836308 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-03 14:53:24.127950 | debian-bookworm | ok 2026-05-03 14:53:24.136607 | 2026-05-03 14:53:24.136778 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-03 14:53:24.854630 | debian-bookworm | changed 2026-05-03 14:53:24.866961 | 2026-05-03 14:53:24.867147 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-03 14:53:25.581615 | debian-bookworm | changed 2026-05-03 14:53:25.590771 | 2026-05-03 14:53:25.590940 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-03 14:53:25.615634 | debian-bookworm | skipping: Conditional result was False 2026-05-03 14:53:25.625724 | 2026-05-03 14:53:25.625869 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-03 14:53:26.076987 | debian-bookworm -> localhost | changed 2026-05-03 14:53:26.088820 | 2026-05-03 14:53:26.088977 | TASK [add-build-sshkey : Add back temp key] 2026-05-03 14:53:26.468206 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/665ff386ce894da39a2d9266d1923a27/work/665ff386ce894da39a2d9266d1923a27_id_rsa (zuul-build-sshkey) 2026-05-03 14:53:26.468775 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.018438 2026-05-03 14:53:26.486299 | 2026-05-03 14:53:26.486774 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-03 14:53:26.908915 | debian-bookworm | ok 2026-05-03 14:53:26.919104 | 2026-05-03 14:53:26.919276 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-03 14:53:26.956131 | debian-bookworm | skipping: Conditional result was False 2026-05-03 14:53:26.975825 | 2026-05-03 14:53:26.976537 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-03 14:53:27.484760 | debian-bookworm | ok 2026-05-03 14:53:27.500831 | 2026-05-03 14:53:27.501022 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-03 14:53:27.543177 | debian-bookworm | ok 2026-05-03 14:53:27.549425 | 2026-05-03 14:53:27.549553 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-03 14:53:27.851577 | debian-bookworm -> localhost | ok 2026-05-03 14:53:27.861364 | 2026-05-03 14:53:27.861506 | TASK [validate-host : Collect information about the host] 2026-05-03 14:53:28.990402 | debian-bookworm | ok 2026-05-03 14:53:29.000648 | 2026-05-03 14:53:29.000768 | TASK [validate-host : Sanitize hostname] 2026-05-03 14:53:29.130548 | debian-bookworm | ok 2026-05-03 14:53:29.174051 | 2026-05-03 14:53:29.174197 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-03 14:53:29.786105 | debian-bookworm -> localhost | changed 2026-05-03 14:53:29.792158 | 2026-05-03 14:53:29.792286 | TASK [validate-host : Collect information about zuul worker] 2026-05-03 14:53:30.222010 | debian-bookworm | ok 2026-05-03 14:53:30.230359 | 2026-05-03 14:53:30.230519 | TASK [validate-host : Write out all zuul information for each host] 2026-05-03 14:53:30.801725 | debian-bookworm -> localhost | changed 2026-05-03 14:53:30.812015 | 2026-05-03 14:53:30.812121 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-03 14:53:31.102551 | debian-bookworm | ok 2026-05-03 14:53:31.111841 | 2026-05-03 14:53:31.112021 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-03 14:53:32.124073 | debian-bookworm | changed: 2026-05-03 14:53:32.124412 | debian-bookworm | cd+++++++++ src/ 2026-05-03 14:53:32.124474 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-03 14:53:32.124517 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-03 14:53:32.124555 | debian-bookworm | cd+++++++++ src/github.com/osism/netbox-manager/ 2026-05-03 14:53:32.124590 | debian-bookworm | netbox_manager/requirements.yml 2026-05-03 14:53:32.125043 | debian-bookworm | cL+++++++++ src/github.com/osism/netbox-manager/settings.toml.sample -> netbox_manager/settings.toml.sample 2026-05-03 14:53:32.125087 | debian-bookworm | ", 2026-05-03 14:54:07.833517 | debian-bookworm | "warnings": [ 2026-05-03 14:54:07.833556 | debian-bookworm | "Module remote_tmp /root/.ansible/tmp did not exist and was created with a mode of 0700, this may cause issues when running as another user. To avoid this, create the remote_tmp dir with the correct permissions manually" 2026-05-03 14:54:07.833612 | debian-bookworm | ] 2026-05-03 14:54:07.833645 | debian-bookworm | } failure 2026-05-03 14:54:07.838000 | 2026-05-03 14:54:07.838129 | PLAY RECAP 2026-05-03 14:54:07.839110 | debian-bookworm | ok: 8 changed: 5 unreachable: 0 failed: 1 skipped: 4 rescued: 0 ignored: 0 2026-05-03 14:54:07.839511 | 2026-05-03 14:54:07.978956 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/osism/netbox-manager/playbooks/pre.yml@main] 2026-05-03 14:54:07.980057 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-03 14:54:08.731594 | 2026-05-03 14:54:08.731772 | PLAY [Base post-fetch] 2026-05-03 14:54:08.746665 | 2026-05-03 14:54:08.746811 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-03 14:54:08.813034 | debian-bookworm | skipping: Conditional result was False 2026-05-03 14:54:08.826827 | 2026-05-03 14:54:08.827162 | TASK [fetch-output : Set log path for single node] 2026-05-03 14:54:08.876124 | debian-bookworm | ok 2026-05-03 14:54:08.885043 | 2026-05-03 14:54:08.885208 | LOOP [fetch-output : Ensure local output dirs] 2026-05-03 14:54:09.397558 | debian-bookworm -> localhost | ok: "/var/lib/zuul/builds/665ff386ce894da39a2d9266d1923a27/work/logs" 2026-05-03 14:54:09.695231 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/665ff386ce894da39a2d9266d1923a27/work/artifacts" 2026-05-03 14:54:09.984516 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/665ff386ce894da39a2d9266d1923a27/work/docs" 2026-05-03 14:54:10.009481 | 2026-05-03 14:54:10.009685 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-03 14:54:10.909418 | debian-bookworm | changed: 2026-05-03 14:54:10.909920 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-03 14:54:10.910023 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-03 14:54:10.910113 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-03 14:54:10.910240 | debian-bookworm | changed: All items complete 2026-05-03 14:54:10.910377 | 2026-05-03 14:54:11.502923 | debian-bookworm | changed: .d..t...... ./ 2026-05-03 14:54:12.104990 | debian-bookworm | changed: .d..t...... ./ 2026-05-03 14:54:12.135038 | 2026-05-03 14:54:12.135234 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-03 14:54:12.694777 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.009816 2026-05-03 14:54:12.976321 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.010333 2026-05-03 14:54:13.000526 | 2026-05-03 14:54:13.000668 | PLAY RECAP 2026-05-03 14:54:13.000749 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-03 14:54:13.000790 | 2026-05-03 14:54:13.135510 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-03 14:54:13.137941 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-03 14:54:13.973331 | 2026-05-03 14:54:13.973585 | PLAY [Base post] 2026-05-03 14:54:13.989317 | 2026-05-03 14:54:13.989478 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-03 14:54:14.553547 | debian-bookworm | changed 2026-05-03 14:54:14.562935 | 2026-05-03 14:54:14.563087 | PLAY RECAP 2026-05-03 14:54:14.563170 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-03 14:54:14.563251 | 2026-05-03 14:54:14.693433 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-03 14:54:14.695699 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-03 14:54:15.510476 | 2026-05-03 14:54:15.510672 | PLAY [Base post-logs] 2026-05-03 14:54:15.521582 | 2026-05-03 14:54:15.521721 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-03 14:54:16.003927 | localhost | changed 2026-05-03 14:54:16.029902 | 2026-05-03 14:54:16.030234 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-03 14:54:16.063094 | localhost | ok 2026-05-03 14:54:16.070058 | 2026-05-03 14:54:16.070266 | TASK [Set zuul-log-path fact] 2026-05-03 14:54:16.091529 | localhost | ok 2026-05-03 14:54:16.106474 | 2026-05-03 14:54:16.106694 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-03 14:54:16.147516 | localhost | ok 2026-05-03 14:54:16.155101 | 2026-05-03 14:54:16.155327 | TASK [upload-logs : Create log directories] 2026-05-03 14:54:16.701811 | localhost | changed 2026-05-03 14:54:16.709915 | 2026-05-03 14:54:16.710236 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-03 14:54:17.244849 | localhost -> localhost | ok: Runtime: 0:00:00.008096 2026-05-03 14:54:17.251892 | 2026-05-03 14:54:17.252041 | TASK [upload-logs : Upload logs to log server] 2026-05-03 14:54:17.881606 | localhost | Output suppressed because no_log was given 2026-05-03 14:54:17.885744 | 2026-05-03 14:54:17.885925 | LOOP [upload-logs : Compress console log and json output] 2026-05-03 14:54:17.944811 | localhost | skipping: Conditional result was False 2026-05-03 14:54:17.949824 | localhost | skipping: Conditional result was False 2026-05-03 14:54:17.956843 | 2026-05-03 14:54:17.957031 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-03 14:54:18.013836 | localhost | skipping: Conditional result was False 2026-05-03 14:54:18.014384 | 2026-05-03 14:54:18.017986 | localhost | skipping: Conditional result was False 2026-05-03 14:54:18.030200 | 2026-05-03 14:54:18.030414 | LOOP [upload-logs : Upload console log and json output]