2026-09-29 03:18:30.648493 | Job console starting 2026-09-29 03:18:30.676040 | Updating git repos 2026-09-29 03:18:30.717513 | Cloning repos into workspace 2026-09-29 03:18:30.755552 | Restoring repo states 2026-09-29 03:18:30.765662 | Merging changes 2026-09-29 03:18:30.765679 | Checking out repos 2026-09-29 03:18:30.798477 | Preparing playbooks 2026-09-29 03:18:31.644421 | Running Ansible setup 2026-09-29 03:18:36.127219 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-29 03:18:37.070101 | 2026-09-29 03:18:37.070267 | PLAY [Base pre] 2026-09-29 03:18:37.092116 | 2026-09-29 03:18:37.092254 | TASK [Setup log path fact] 2026-09-29 03:18:37.113213 | debian-bookworm | ok 2026-09-29 03:18:37.133899 | 2026-09-29 03:18:37.134051 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-29 03:18:37.163321 | debian-bookworm | ok 2026-09-29 03:18:37.173225 | 2026-09-29 03:18:37.173326 | TASK [emit-job-header : Print job information] 2026-09-29 03:18:37.212174 | # Job Information 2026-09-29 03:18:37.212354 | Ansible Version: 2.16.14 2026-09-29 03:18:37.212389 | Job: python-black 2026-09-29 03:18:37.212421 | Pipeline: periodic-daily 2026-09-29 03:18:37.212443 | Executor: 521e9411259a 2026-09-29 03:18:37.212463 | Triggered by: https://github.com/osism/netbox-plugin-osism 2026-09-29 03:18:37.212484 | Event ID: 38833397984445ec8838209b817c4ee1 2026-09-29 03:18:37.217502 | 2026-09-29 03:18:37.217643 | LOOP [emit-job-header : Print node information] 2026-09-29 03:18:37.330635 | debian-bookworm | ok: 2026-09-29 03:18:37.330906 | debian-bookworm | # Node Information 2026-09-29 03:18:37.330945 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-29 03:18:37.330971 | debian-bookworm | Hostname: debian 2026-09-29 03:18:37.330993 | debian-bookworm | Username: zuul 2026-09-29 03:18:37.331015 | debian-bookworm | Distro: Debian 12.15 2026-09-29 03:18:37.331039 | debian-bookworm | Provider: regiocloud-a 2026-09-29 03:18:37.331059 | debian-bookworm | Region: 2026-09-29 03:18:37.331080 | debian-bookworm | Label: debian-bookworm 2026-09-29 03:18:37.331100 | debian-bookworm | Product Name: OpenStack Nova 2026-09-29 03:18:37.331119 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe26:db27 2026-09-29 03:18:37.347076 | 2026-09-29 03:18:37.347642 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-29 03:18:38.065425 | debian-bookworm -> localhost | changed 2026-09-29 03:18:38.075661 | 2026-09-29 03:18:38.075822 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-29 03:18:40.144918 | debian-bookworm -> localhost | changed 2026-09-29 03:18:40.157208 | 2026-09-29 03:18:40.157306 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-29 03:18:40.372827 | debian-bookworm -> localhost | ok 2026-09-29 03:18:40.378989 | 2026-09-29 03:18:40.379089 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-29 03:18:40.398232 | debian-bookworm | ok 2026-09-29 03:18:40.413308 | debian-bookworm | included: /var/lib/zuul/builds/d72fbdc04cb24dd2b04492181f0e155d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-29 03:18:40.419307 | 2026-09-29 03:18:40.419391 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-29 03:18:42.262221 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-29 03:18:42.262474 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/d72fbdc04cb24dd2b04492181f0e155d/work/d72fbdc04cb24dd2b04492181f0e155d_id_rsa 2026-09-29 03:18:42.262512 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/d72fbdc04cb24dd2b04492181f0e155d/work/d72fbdc04cb24dd2b04492181f0e155d_id_rsa.pub 2026-09-29 03:18:42.262538 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-29 03:18:42.262562 | debian-bookworm -> localhost | SHA256:Ulf+ZuBWwxvNZwJ2/raXKPK7UVDuDmlQg5EnndfFizQ zuul-build-sshkey 2026-09-29 03:18:42.262584 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-29 03:18:42.262616 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-29 03:18:42.262638 | debian-bookworm -> localhost | | .=o=.o.o| 2026-09-29 03:18:42.262659 | debian-bookworm -> localhost | | +.B+E +.| 2026-09-29 03:18:42.262680 | debian-bookworm -> localhost | | ..+.=.X *| 2026-09-29 03:18:42.262699 | debian-bookworm -> localhost | | . ...+= O.| 2026-09-29 03:18:42.262718 | debian-bookworm -> localhost | | . S +oo= o| 2026-09-29 03:18:42.262744 | debian-bookworm -> localhost | | . ..+o..o| 2026-09-29 03:18:42.262776 | debian-bookworm -> localhost | | . o o o.| 2026-09-29 03:18:42.262797 | debian-bookworm -> localhost | | o o .| 2026-09-29 03:18:42.262818 | debian-bookworm -> localhost | | +o | 2026-09-29 03:18:42.262850 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-29 03:18:42.262904 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.388091 2026-09-29 03:18:42.270221 | 2026-09-29 03:18:42.270318 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-29 03:18:42.298805 | debian-bookworm | ok 2026-09-29 03:18:42.322700 | debian-bookworm | included: /var/lib/zuul/builds/d72fbdc04cb24dd2b04492181f0e155d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-29 03:18:42.342626 | 2026-09-29 03:18:42.342726 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-29 03:18:42.386085 | debian-bookworm | skipping: Conditional result was False 2026-09-29 03:18:42.392845 | 2026-09-29 03:18:42.392947 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-29 03:18:43.066838 | debian-bookworm | changed 2026-09-29 03:18:43.071932 | 2026-09-29 03:18:43.072008 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-29 03:18:43.294042 | debian-bookworm | ok 2026-09-29 03:18:43.300840 | 2026-09-29 03:18:43.300919 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-29 03:18:43.953966 | debian-bookworm | changed 2026-09-29 03:18:43.962526 | 2026-09-29 03:18:43.962607 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-29 03:18:44.617983 | debian-bookworm | changed 2026-09-29 03:18:44.623528 | 2026-09-29 03:18:44.623604 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-29 03:18:44.637110 | debian-bookworm | skipping: Conditional result was False 2026-09-29 03:18:44.642201 | 2026-09-29 03:18:44.642272 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-29 03:18:45.100421 | debian-bookworm -> localhost | changed 2026-09-29 03:18:45.116073 | 2026-09-29 03:18:45.116166 | TASK [add-build-sshkey : Add back temp key] 2026-09-29 03:18:45.633936 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/d72fbdc04cb24dd2b04492181f0e155d/work/d72fbdc04cb24dd2b04492181f0e155d_id_rsa (zuul-build-sshkey) 2026-09-29 03:18:45.634119 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.013799 2026-09-29 03:18:45.640160 | 2026-09-29 03:18:45.640234 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-29 03:18:46.015593 | debian-bookworm | ok 2026-09-29 03:18:46.021816 | 2026-09-29 03:18:46.021905 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-29 03:18:46.065936 | debian-bookworm | skipping: Conditional result was False 2026-09-29 03:18:46.075569 | 2026-09-29 03:18:46.075655 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-29 03:18:46.441820 | debian-bookworm | ok 2026-09-29 03:18:46.450494 | 2026-09-29 03:18:46.450570 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-29 03:18:46.477134 | debian-bookworm | ok 2026-09-29 03:18:46.481341 | 2026-09-29 03:18:46.481413 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-29 03:18:46.909741 | debian-bookworm -> localhost | ok 2026-09-29 03:18:46.916377 | 2026-09-29 03:18:46.916452 | TASK [validate-host : Collect information about the host] 2026-09-29 03:18:47.953332 | debian-bookworm | ok 2026-09-29 03:18:47.962603 | 2026-09-29 03:18:47.962676 | TASK [validate-host : Sanitize hostname] 2026-09-29 03:18:48.051390 | debian-bookworm | ok 2026-09-29 03:18:48.055643 | 2026-09-29 03:18:48.055791 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-29 03:18:48.571229 | debian-bookworm -> localhost | changed 2026-09-29 03:18:48.579009 | 2026-09-29 03:18:48.579114 | TASK [validate-host : Collect information about zuul worker] 2026-09-29 03:18:48.967431 | debian-bookworm | ok 2026-09-29 03:18:48.971889 | 2026-09-29 03:18:48.971978 | TASK [validate-host : Write out all zuul information for each host] 2026-09-29 03:18:49.672603 | debian-bookworm -> localhost | changed 2026-09-29 03:18:49.680564 | 2026-09-29 03:18:49.680685 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-29 03:18:49.954213 | debian-bookworm | ok 2026-09-29 03:18:49.958652 | 2026-09-29 03:18:49.958726 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-29 03:18:50.976930 | debian-bookworm | changed: 2026-09-29 03:18:50.977102 | debian-bookworm | cd+++++++++ src/ 2026-09-29 03:18:50.977250 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-29 03:18:50.977273 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-29 03:18:50.977292 | debian-bookworm | cd+++++++++ src/github.com/osism/netbox-plugin-osism/ 2026-09-29 03:18:50.977310 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/d72fbdc04cb24dd2b04492181f0e155d/work/logs" 2026-09-29 03:19:53.192738 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/d72fbdc04cb24dd2b04492181f0e155d/work/artifacts" 2026-09-29 03:19:53.486137 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/d72fbdc04cb24dd2b04492181f0e155d/work/docs" 2026-09-29 03:19:53.493778 | 2026-09-29 03:19:53.493854 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-29 03:19:54.181143 | debian-bookworm | changed: 2026-09-29 03:19:54.181299 | debian-bookworm | .d..t...... ./ 2026-09-29 03:19:54.181329 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-29 03:19:54.181348 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-29 03:19:54.181366 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-29 03:19:54.181383 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-29 03:19:54.181399 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-29 03:19:54.181430 | debian-bookworm | changed: All items complete 2026-09-29 03:19:54.181447 | 2026-09-29 03:19:54.840508 | debian-bookworm | changed: .d..t...... ./ 2026-09-29 03:19:55.414569 | debian-bookworm | changed: .d..t...... ./ 2026-09-29 03:19:55.433322 | 2026-09-29 03:19:55.433429 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-29 03:19:55.452880 | debian-bookworm | skipping: Conditional result was False 2026-09-29 03:19:55.457760 | debian-bookworm | skipping: Conditional result was False 2026-09-29 03:19:55.473087 | 2026-09-29 03:19:55.473157 | PLAY RECAP 2026-09-29 03:19:55.473203 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-09-29 03:19:55.473227 | 2026-09-29 03:19:55.580176 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-29 03:19:55.580933 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-29 03:19:56.378773 | 2026-09-29 03:19:56.378875 | PLAY [Base post] 2026-09-29 03:19:56.391123 | 2026-09-29 03:19:56.391219 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-29 03:19:57.206556 | debian-bookworm | changed 2026-09-29 03:19:57.265403 | 2026-09-29 03:19:57.266851 | PLAY RECAP 2026-09-29 03:19:57.268063 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-29 03:19:57.269120 | 2026-09-29 03:19:57.683168 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-29 03:19:57.683909 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-29 03:19:58.611146 | 2026-09-29 03:19:58.613430 | PLAY [Base post-logs] 2026-09-29 03:19:58.627238 | 2026-09-29 03:19:58.627365 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-29 03:19:59.097299 | localhost | changed 2026-09-29 03:19:59.105217 | 2026-09-29 03:19:59.105319 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-29 03:19:59.128933 | localhost | ok 2026-09-29 03:19:59.131196 | 2026-09-29 03:19:59.131263 | TASK [Set zuul-log-path fact] 2026-09-29 03:19:59.154965 | localhost | ok 2026-09-29 03:19:59.160978 | 2026-09-29 03:19:59.161056 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-29 03:19:59.184486 | localhost | ok 2026-09-29 03:19:59.186662 | 2026-09-29 03:19:59.186755 | TASK [upload-logs : Create log directories] 2026-09-29 03:19:59.671246 | localhost | changed 2026-09-29 03:19:59.673345 | 2026-09-29 03:19:59.673415 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-29 03:20:00.053623 | localhost -> localhost | ok: Runtime: 0:00:00.017420 2026-09-29 03:20:00.056556 | 2026-09-29 03:20:00.056670 | TASK [upload-logs : Upload logs to log server] 2026-09-29 03:20:00.549812 | localhost | Output suppressed because no_log was given 2026-09-29 03:20:00.551178 | 2026-09-29 03:20:00.551247 | LOOP [upload-logs : Compress console log and json output] 2026-09-29 03:20:00.624556 | localhost | skipping: Conditional result was False 2026-09-29 03:20:00.629072 | localhost | skipping: Conditional result was False 2026-09-29 03:20:00.637030 | 2026-09-29 03:20:00.637105 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-29 03:20:00.701613 | localhost | skipping: Conditional result was False 2026-09-29 03:20:00.701818 | 2026-09-29 03:20:00.706773 | localhost | skipping: Conditional result was False 2026-09-29 03:20:00.719283 | 2026-09-29 03:20:00.719357 | LOOP [upload-logs : Upload console log and json output]