2025-11-05 10:22:07.446481 | Job console starting 2025-11-05 10:22:07.467556 | Updating git repos 2025-11-05 10:22:07.497966 | Cloning repos into workspace 2025-11-05 10:22:07.552809 | Restoring repo states 2025-11-05 10:22:07.559438 | Merging changes 2025-11-05 10:22:07.559462 | Checking out repos 2025-11-05 10:22:07.614375 | Preparing playbooks 2025-11-05 10:22:08.556752 | Running Ansible setup 2025-11-05 10:22:15.062557 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-11-05 10:22:15.998327 | 2025-11-05 10:22:15.998499 | PLAY [Base pre] 2025-11-05 10:22:16.060283 | 2025-11-05 10:22:16.060453 | TASK [Setup log path fact] 2025-11-05 10:22:16.090625 | noble | ok 2025-11-05 10:22:16.118619 | 2025-11-05 10:22:16.118787 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-05 10:22:16.180866 | noble | ok 2025-11-05 10:22:16.202161 | 2025-11-05 10:22:16.202294 | TASK [emit-job-header : Print job information] 2025-11-05 10:22:16.243774 | # Job Information 2025-11-05 10:22:16.243969 | Ansible Version: 2.16.14 2025-11-05 10:22:16.244041 | Job: openstack-ironic-images-publish-osism-vyos 2025-11-05 10:22:16.244078 | Pipeline: post 2025-11-05 10:22:16.244103 | Executor: 521e9411259a 2025-11-05 10:22:16.244124 | Triggered by: https://github.com/osism/openstack-ironic-images 2025-11-05 10:22:16.244146 | Event ID: 77aa5a4dc69045109810aba605017b66 2025-11-05 10:22:16.250957 | 2025-11-05 10:22:16.251108 | LOOP [emit-job-header : Print node information] 2025-11-05 10:22:16.377038 | noble | ok: 2025-11-05 10:22:16.377319 | noble | # Node Information 2025-11-05 10:22:16.377357 | noble | Inventory Hostname: noble 2025-11-05 10:22:16.377383 | noble | Hostname: ubuntu 2025-11-05 10:22:16.377406 | noble | Username: zuul 2025-11-05 10:22:16.377426 | noble | Distro: Ubuntu 24.04 2025-11-05 10:22:16.377450 | noble | Provider: regiocloud-a 2025-11-05 10:22:16.377471 | noble | Region: 2025-11-05 10:22:16.377492 | noble | Label: ubuntu-noble-uefi 2025-11-05 10:22:16.377513 | noble | Product Name: OpenStack Nova 2025-11-05 10:22:16.377533 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe72:bcb8 2025-11-05 10:22:16.395166 | 2025-11-05 10:22:16.395313 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-05 10:22:17.082600 | noble -> localhost | changed 2025-11-05 10:22:17.096685 | 2025-11-05 10:22:17.096827 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-05 10:22:18.479299 | noble -> localhost | changed 2025-11-05 10:22:18.490352 | 2025-11-05 10:22:18.490450 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-05 10:22:18.814882 | noble -> localhost | ok 2025-11-05 10:22:18.820347 | 2025-11-05 10:22:18.820430 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-05 10:22:18.857584 | noble | ok 2025-11-05 10:22:18.878254 | noble | included: /var/lib/zuul/builds/8ec15227b4184b1789b94306956ff222/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-05 10:22:18.893579 | 2025-11-05 10:22:18.893663 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-05 10:22:19.867835 | noble -> localhost | Generating public/private rsa key pair. 2025-11-05 10:22:19.868011 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/8ec15227b4184b1789b94306956ff222/work/8ec15227b4184b1789b94306956ff222_id_rsa 2025-11-05 10:22:19.868045 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/8ec15227b4184b1789b94306956ff222/work/8ec15227b4184b1789b94306956ff222_id_rsa.pub 2025-11-05 10:22:19.868069 | noble -> localhost | The key fingerprint is: 2025-11-05 10:22:19.868090 | noble -> localhost | SHA256:hmyoeuqYcXIdvclsmb6995YIsfrQFL5a4p9L84FEGt0 zuul-build-sshkey 2025-11-05 10:22:19.868110 | noble -> localhost | The key's randomart image is: 2025-11-05 10:22:19.868128 | noble -> localhost | +---[RSA 3072]----+ 2025-11-05 10:22:19.868146 | noble -> localhost | | | 2025-11-05 10:22:19.868164 | noble -> localhost | | . . | 2025-11-05 10:22:19.868189 | noble -> localhost | | . + E | 2025-11-05 10:22:19.868207 | noble -> localhost | | + *.. | 2025-11-05 10:22:19.868224 | noble -> localhost | | o * So | 2025-11-05 10:22:19.868243 | noble -> localhost | | o = Xoo | 2025-11-05 10:22:19.868260 | noble -> localhost | |o + . @.B... . | 2025-11-05 10:22:19.868277 | noble -> localhost | |.B. +.B +o.o | 2025-11-05 10:22:19.868295 | noble -> localhost | |*+ =+B+.o. | 2025-11-05 10:22:19.868312 | noble -> localhost | +----[SHA256]-----+ 2025-11-05 10:22:19.868350 | noble -> localhost | ok: Runtime: 0:00:00.435458 2025-11-05 10:22:19.874528 | 2025-11-05 10:22:19.874607 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-05 10:22:19.891354 | noble | ok 2025-11-05 10:22:19.900018 | noble | included: /var/lib/zuul/builds/8ec15227b4184b1789b94306956ff222/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-05 10:22:19.907239 | 2025-11-05 10:22:19.907314 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-05 10:22:19.919755 | noble | skipping: Conditional result was False 2025-11-05 10:22:19.925842 | 2025-11-05 10:22:19.925919 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-05 10:22:20.523390 | noble | changed 2025-11-05 10:22:20.532895 | 2025-11-05 10:22:20.532999 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-05 10:22:20.842204 | noble | ok 2025-11-05 10:22:20.851385 | 2025-11-05 10:22:20.851491 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-05 10:22:21.637806 | noble | changed 2025-11-05 10:22:21.644341 | 2025-11-05 10:22:21.644438 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-05 10:22:22.427299 | noble | changed 2025-11-05 10:22:22.433865 | 2025-11-05 10:22:22.433956 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-05 10:22:22.457290 | noble | skipping: Conditional result was False 2025-11-05 10:22:22.465701 | 2025-11-05 10:22:22.465793 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-05 10:22:22.932847 | noble -> localhost | changed 2025-11-05 10:22:22.950483 | 2025-11-05 10:22:22.950590 | TASK [add-build-sshkey : Add back temp key] 2025-11-05 10:22:23.259545 | noble -> localhost | Identity added: /var/lib/zuul/builds/8ec15227b4184b1789b94306956ff222/work/8ec15227b4184b1789b94306956ff222_id_rsa (zuul-build-sshkey) 2025-11-05 10:22:23.259775 | noble -> localhost | ok: Runtime: 0:00:00.031672 2025-11-05 10:22:23.266857 | 2025-11-05 10:22:23.266942 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-05 10:22:23.806293 | noble | ok 2025-11-05 10:22:23.811721 | 2025-11-05 10:22:23.811798 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-05 10:22:23.834539 | noble | skipping: Conditional result was False 2025-11-05 10:22:23.866566 | 2025-11-05 10:22:23.866654 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-05 10:22:24.298806 | noble | ok 2025-11-05 10:22:24.309453 | 2025-11-05 10:22:24.309537 | TASK [validate-host : Define zuul_info_dir fact] 2025-11-05 10:22:24.338108 | noble | ok 2025-11-05 10:22:24.344867 | 2025-11-05 10:22:24.344954 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-11-05 10:22:24.654185 | noble -> localhost | ok 2025-11-05 10:22:24.661137 | 2025-11-05 10:22:24.661233 | TASK [validate-host : Collect information about the host] 2025-11-05 10:22:26.090703 | noble | ok 2025-11-05 10:22:26.105210 | 2025-11-05 10:22:26.105330 | TASK [validate-host : Sanitize hostname] 2025-11-05 10:22:26.164560 | noble | ok 2025-11-05 10:22:26.170365 | 2025-11-05 10:22:26.170485 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-11-05 10:22:26.776218 | noble -> localhost | changed 2025-11-05 10:22:26.783215 | 2025-11-05 10:22:26.783338 | TASK [validate-host : Collect information about zuul worker] 2025-11-05 10:22:27.337255 | noble | ok 2025-11-05 10:22:27.342783 | 2025-11-05 10:22:27.342935 | TASK [validate-host : Write out all zuul information for each host] 2025-11-05 10:22:27.994140 | noble -> localhost | changed 2025-11-05 10:22:28.005570 | 2025-11-05 10:22:28.005686 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-11-05 10:22:28.328297 | noble | ok 2025-11-05 10:22:28.340746 | 2025-11-05 10:22:28.340883 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-11-05 10:22:29.290320 | noble | changed: 2025-11-05 10:22:29.290537 | noble | cd+++++++++ src/ 2025-11-05 10:22:29.290573 | noble | cd+++++++++ src/github.com/ 2025-11-05 10:22:29.290597 | noble | cd+++++++++ src/github.com/osism/ 2025-11-05 10:22:29.290619 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-11-05 10:22:29.290639 | noble | localhost | ok: "/var/lib/zuul/builds/8ec15227b4184b1789b94306956ff222/work/logs" 2025-11-05 10:25:00.413233 | noble -> localhost | changed: "/var/lib/zuul/builds/8ec15227b4184b1789b94306956ff222/work/artifacts" 2025-11-05 10:25:00.684284 | noble -> localhost | changed: "/var/lib/zuul/builds/8ec15227b4184b1789b94306956ff222/work/docs" 2025-11-05 10:25:00.711282 | 2025-11-05 10:25:00.711444 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-05 10:25:02.077669 | noble | changed: 2025-11-05 10:25:02.078029 | noble | .d..t...... ./ 2025-11-05 10:25:02.078095 | noble | >f+++++++++ osism-vyos.log 2025-11-05 10:25:02.078171 | noble | changed: All items complete 2025-11-05 10:25:02.078221 | 2025-11-05 10:25:02.762938 | noble | changed: .d..t...... ./ 2025-11-05 10:25:03.461211 | noble | changed: .d..t...... ./ 2025-11-05 10:25:03.487807 | 2025-11-05 10:25:03.487941 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-11-05 10:25:03.523683 | noble | skipping: Conditional result was False 2025-11-05 10:25:03.526953 | noble | skipping: Conditional result was False 2025-11-05 10:25:03.551682 | 2025-11-05 10:25:03.551791 | PLAY RECAP 2025-11-05 10:25:03.551869 | noble | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-11-05 10:25:03.551912 | 2025-11-05 10:25:03.670153 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-11-05 10:25:03.673845 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-05 10:25:04.427206 | 2025-11-05 10:25:04.427358 | PLAY [Base post] 2025-11-05 10:25:04.442401 | 2025-11-05 10:25:04.442535 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-11-05 10:25:05.093236 | noble | changed 2025-11-05 10:25:05.102658 | 2025-11-05 10:25:05.102765 | PLAY RECAP 2025-11-05 10:25:05.102855 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-05 10:25:05.102925 | 2025-11-05 10:25:05.221556 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-05 10:25:05.222483 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-11-05 10:25:05.987426 | 2025-11-05 10:25:05.987583 | PLAY [Base post-logs] 2025-11-05 10:25:05.998121 | 2025-11-05 10:25:05.998251 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-05 10:25:06.459564 | localhost | changed 2025-11-05 10:25:06.471796 | 2025-11-05 10:25:06.472003 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-05 10:25:06.497093 | localhost | ok 2025-11-05 10:25:06.500277 | 2025-11-05 10:25:06.500375 | TASK [Set zuul-log-path fact] 2025-11-05 10:25:06.515032 | localhost | ok 2025-11-05 10:25:06.523126 | 2025-11-05 10:25:06.523235 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-05 10:25:06.559632 | localhost | ok 2025-11-05 10:25:06.566432 | 2025-11-05 10:25:06.566606 | TASK [upload-logs : Create log directories] 2025-11-05 10:25:07.062106 | localhost | changed 2025-11-05 10:25:07.065110 | 2025-11-05 10:25:07.065223 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-11-05 10:25:07.566075 | localhost -> localhost | ok: Runtime: 0:00:00.006913 2025-11-05 10:25:07.570300 | 2025-11-05 10:25:07.570417 | TASK [upload-logs : Upload logs to log server] 2025-11-05 10:25:08.126463 | localhost | Output suppressed because no_log was given 2025-11-05 10:25:08.129831 | 2025-11-05 10:25:08.130018 | LOOP [upload-logs : Compress console log and json output] 2025-11-05 10:25:08.178120 | localhost | skipping: Conditional result was False 2025-11-05 10:25:08.183138 | localhost | skipping: Conditional result was False 2025-11-05 10:25:08.189711 | 2025-11-05 10:25:08.189892 | LOOP [upload-logs : Upload compressed console log and json output] 2025-11-05 10:25:08.234939 | localhost | skipping: Conditional result was False 2025-11-05 10:25:08.235598 | 2025-11-05 10:25:08.238694 | localhost | skipping: Conditional result was False 2025-11-05 10:25:08.253132 | 2025-11-05 10:25:08.253342 | LOOP [upload-logs : Upload console log and json output]