2025-10-26 22:42:20.397023 | Job console starting 2025-10-26 22:42:20.407140 | Updating git repos 2025-10-26 22:42:20.438853 | Cloning repos into workspace 2025-10-26 22:42:20.469748 | Restoring repo states 2025-10-26 22:42:20.475528 | Merging changes 2025-10-26 22:42:20.965324 | Checking out repos 2025-10-26 22:42:21.009911 | Preparing playbooks 2025-10-26 22:42:21.626153 | Running Ansible setup 2025-10-26 22:42:27.801138 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-26 22:42:29.023688 | 2025-10-26 22:42:29.023808 | PLAY [Base pre] 2025-10-26 22:42:29.054311 | 2025-10-26 22:42:29.054423 | TASK [Setup log path fact] 2025-10-26 22:42:29.084647 | noble | ok 2025-10-26 22:42:29.111913 | 2025-10-26 22:42:29.112035 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-26 22:42:29.159416 | noble | ok 2025-10-26 22:42:29.168538 | 2025-10-26 22:42:29.168620 | TASK [emit-job-header : Print job information] 2025-10-26 22:42:29.206954 | # Job Information 2025-10-26 22:42:29.207134 | Ansible Version: 2.16.14 2025-10-26 22:42:29.207164 | Job: openstack-ironic-images-build-osism-vyos 2025-10-26 22:42:29.207194 | Pipeline: check 2025-10-26 22:42:29.207217 | Executor: 521e9411259a 2025-10-26 22:42:29.207234 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/155 2025-10-26 22:42:29.207251 | Event ID: 97890320-b2bc-11f0-8847-c91828222428 2025-10-26 22:42:29.213010 | 2025-10-26 22:42:29.213110 | LOOP [emit-job-header : Print node information] 2025-10-26 22:42:29.383329 | noble | ok: 2025-10-26 22:42:29.383497 | noble | # Node Information 2025-10-26 22:42:29.383533 | noble | Inventory Hostname: noble 2025-10-26 22:42:29.383557 | noble | Hostname: ubuntu 2025-10-26 22:42:29.383578 | noble | Username: zuul 2025-10-26 22:42:29.383598 | noble | Distro: Ubuntu 24.04 2025-10-26 22:42:29.383621 | noble | Provider: regiocloud-a 2025-10-26 22:42:29.383642 | noble | Region: 2025-10-26 22:42:29.383663 | noble | Label: ubuntu-noble-uefi 2025-10-26 22:42:29.383683 | noble | Product Name: OpenStack Nova 2025-10-26 22:42:29.383703 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe00:b5d 2025-10-26 22:42:29.395082 | 2025-10-26 22:42:29.395170 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-26 22:42:30.199106 | noble -> localhost | changed 2025-10-26 22:42:30.205636 | 2025-10-26 22:42:30.205730 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-26 22:42:31.363243 | noble -> localhost | changed 2025-10-26 22:42:31.388041 | 2025-10-26 22:42:31.388140 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-26 22:42:31.763704 | noble -> localhost | ok 2025-10-26 22:42:31.769121 | 2025-10-26 22:42:31.769203 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-26 22:42:31.796328 | noble | ok 2025-10-26 22:42:31.825072 | noble | included: /var/lib/zuul/builds/88f5b71c5cff4fcaafe83bd2b8a5c243/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-26 22:42:31.831109 | 2025-10-26 22:42:31.831173 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-26 22:42:32.963205 | noble -> localhost | Generating public/private rsa key pair. 2025-10-26 22:42:32.963389 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/88f5b71c5cff4fcaafe83bd2b8a5c243/work/88f5b71c5cff4fcaafe83bd2b8a5c243_id_rsa 2025-10-26 22:42:32.963428 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/88f5b71c5cff4fcaafe83bd2b8a5c243/work/88f5b71c5cff4fcaafe83bd2b8a5c243_id_rsa.pub 2025-10-26 22:42:32.963470 | noble -> localhost | The key fingerprint is: 2025-10-26 22:42:32.963498 | noble -> localhost | SHA256:udHafKgmYqOxC4rwGVNH2nxKtIgfDyIWklNXlVjTJFk zuul-build-sshkey 2025-10-26 22:42:32.963521 | noble -> localhost | The key's randomart image is: 2025-10-26 22:42:32.963542 | noble -> localhost | +---[RSA 3072]----+ 2025-10-26 22:42:32.963562 | noble -> localhost | | . ...+=*E | 2025-10-26 22:42:32.963592 | noble -> localhost | | o . . oo. | 2025-10-26 22:42:32.963614 | noble -> localhost | |+. o | 2025-10-26 22:42:32.963634 | noble -> localhost | |.... B . o | 2025-10-26 22:42:32.963654 | noble -> localhost | |..o * * S . | 2025-10-26 22:42:32.963679 | noble -> localhost | |.. + * o * . | 2025-10-26 22:42:32.963701 | noble -> localhost | |o + . o o + . | 2025-10-26 22:42:32.963721 | noble -> localhost | |+o *+ . .. . | 2025-10-26 22:42:32.963742 | noble -> localhost | |o *+ o o. | 2025-10-26 22:42:32.963763 | noble -> localhost | +----[SHA256]-----+ 2025-10-26 22:42:32.963811 | noble -> localhost | ok: Runtime: 0:00:00.711904 2025-10-26 22:42:32.970771 | 2025-10-26 22:42:32.970911 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-26 22:42:32.988888 | noble | ok 2025-10-26 22:42:32.998646 | noble | included: /var/lib/zuul/builds/88f5b71c5cff4fcaafe83bd2b8a5c243/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-26 22:42:33.007585 | 2025-10-26 22:42:33.007663 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-26 22:42:33.021572 | noble | skipping: Conditional result was False 2025-10-26 22:42:33.028721 | 2025-10-26 22:42:33.028799 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-26 22:42:33.636692 | noble | changed 2025-10-26 22:42:33.645506 | 2025-10-26 22:42:33.645608 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-26 22:42:33.988916 | noble | ok 2025-10-26 22:42:33.998712 | 2025-10-26 22:42:33.998829 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-26 22:42:34.816252 | noble | changed 2025-10-26 22:42:34.829431 | 2025-10-26 22:42:34.829582 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-26 22:42:35.573507 | noble | changed 2025-10-26 22:42:35.581660 | 2025-10-26 22:42:35.581759 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-26 22:42:35.604873 | noble | skipping: Conditional result was False 2025-10-26 22:42:35.611278 | 2025-10-26 22:42:35.611365 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-26 22:42:35.968154 | noble -> localhost | changed 2025-10-26 22:42:35.980815 | 2025-10-26 22:42:35.980902 | TASK [add-build-sshkey : Add back temp key] 2025-10-26 22:42:36.300155 | noble -> localhost | Identity added: /var/lib/zuul/builds/88f5b71c5cff4fcaafe83bd2b8a5c243/work/88f5b71c5cff4fcaafe83bd2b8a5c243_id_rsa (zuul-build-sshkey) 2025-10-26 22:42:36.300351 | noble -> localhost | ok: Runtime: 0:00:00.010792 2025-10-26 22:42:36.307015 | 2025-10-26 22:42:36.307102 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-26 22:42:36.745470 | noble | ok 2025-10-26 22:42:36.763321 | 2025-10-26 22:42:36.763431 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-26 22:42:36.786281 | noble | skipping: Conditional result was False 2025-10-26 22:42:36.832881 | 2025-10-26 22:42:36.832995 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-26 22:42:37.245477 | noble | ok 2025-10-26 22:42:37.256615 | 2025-10-26 22:42:37.256966 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-26 22:42:37.295598 | noble | ok 2025-10-26 22:42:37.302163 | 2025-10-26 22:42:37.302246 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-26 22:42:37.546773 | noble -> localhost | ok 2025-10-26 22:42:37.553583 | 2025-10-26 22:42:37.553667 | TASK [validate-host : Collect information about the host] 2025-10-26 22:42:38.950369 | noble | ok 2025-10-26 22:42:38.969296 | 2025-10-26 22:42:38.969386 | TASK [validate-host : Sanitize hostname] 2025-10-26 22:42:39.028140 | noble | ok 2025-10-26 22:42:39.038427 | 2025-10-26 22:42:39.038533 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-26 22:42:39.561671 | noble -> localhost | changed 2025-10-26 22:42:39.567468 | 2025-10-26 22:42:39.567562 | TASK [validate-host : Collect information about zuul worker] 2025-10-26 22:42:40.040833 | noble | ok 2025-10-26 22:42:40.046087 | 2025-10-26 22:42:40.046176 | TASK [validate-host : Write out all zuul information for each host] 2025-10-26 22:42:40.560196 | noble -> localhost | changed 2025-10-26 22:42:40.570087 | 2025-10-26 22:42:40.570172 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-26 22:42:40.919730 | noble | ok 2025-10-26 22:42:40.928393 | 2025-10-26 22:42:40.928511 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-26 22:42:41.862789 | noble | changed: 2025-10-26 22:42:41.863168 | noble | cd+++++++++ src/ 2025-10-26 22:42:41.863207 | noble | cd+++++++++ src/github.com/ 2025-10-26 22:42:41.863232 | noble | cd+++++++++ src/github.com/osism/ 2025-10-26 22:42:41.863255 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-10-26 22:42:41.863276 | noble | localhost | ok: "/var/lib/zuul/builds/88f5b71c5cff4fcaafe83bd2b8a5c243/work/logs" 2025-10-26 22:45:12.731261 | noble -> localhost | changed: "/var/lib/zuul/builds/88f5b71c5cff4fcaafe83bd2b8a5c243/work/artifacts" 2025-10-26 22:45:13.029571 | noble -> localhost | changed: "/var/lib/zuul/builds/88f5b71c5cff4fcaafe83bd2b8a5c243/work/docs" 2025-10-26 22:45:13.055848 | 2025-10-26 22:45:13.056018 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-26 22:45:14.380470 | noble | changed: 2025-10-26 22:45:14.380838 | noble | .d..t...... ./ 2025-10-26 22:45:14.380909 | noble | >f+++++++++ osism-vyos.log 2025-10-26 22:45:14.381010 | noble | changed: All items complete 2025-10-26 22:45:14.381065 | 2025-10-26 22:45:15.041411 | noble | changed: .d..t...... ./ 2025-10-26 22:45:15.700381 | noble | changed: .d..t...... ./ 2025-10-26 22:45:15.730736 | 2025-10-26 22:45:15.730907 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-26 22:45:16.246223 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.009872 2025-10-26 22:45:16.547132 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.009790 2025-10-26 22:45:16.567567 | 2025-10-26 22:45:16.567705 | PLAY RECAP 2025-10-26 22:45:16.567791 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-26 22:45:16.567833 | 2025-10-26 22:45:16.693743 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-10-26 22:45:16.695953 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-26 22:45:17.434141 | 2025-10-26 22:45:17.434299 | PLAY [Base post] 2025-10-26 22:45:17.448684 | 2025-10-26 22:45:17.448809 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-26 22:45:18.106328 | noble | changed 2025-10-26 22:45:18.115753 | 2025-10-26 22:45:18.115872 | PLAY RECAP 2025-10-26 22:45:18.115945 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-26 22:45:18.116019 | 2025-10-26 22:45:18.227534 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-26 22:45:18.229735 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-26 22:45:19.003962 | 2025-10-26 22:45:19.004134 | PLAY [Base post-logs] 2025-10-26 22:45:19.015106 | 2025-10-26 22:45:19.015251 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-26 22:45:19.490795 | localhost | changed 2025-10-26 22:45:19.508552 | 2025-10-26 22:45:19.508734 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-26 22:45:19.547375 | localhost | ok 2025-10-26 22:45:19.553638 | 2025-10-26 22:45:19.553784 | TASK [Set zuul-log-path fact] 2025-10-26 22:45:19.571755 | localhost | ok 2025-10-26 22:45:19.585859 | 2025-10-26 22:45:19.586000 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-26 22:45:19.623665 | localhost | ok 2025-10-26 22:45:19.631057 | 2025-10-26 22:45:19.631263 | TASK [upload-logs : Create log directories] 2025-10-26 22:45:20.129814 | localhost | changed 2025-10-26 22:45:20.135174 | 2025-10-26 22:45:20.135358 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-26 22:45:20.617612 | localhost -> localhost | ok: Runtime: 0:00:00.006887 2025-10-26 22:45:20.622194 | 2025-10-26 22:45:20.622310 | TASK [upload-logs : Upload logs to log server] 2025-10-26 22:45:21.153989 | localhost | Output suppressed because no_log was given 2025-10-26 22:45:21.156057 | 2025-10-26 22:45:21.156173 | LOOP [upload-logs : Compress console log and json output] 2025-10-26 22:45:21.213859 | localhost | skipping: Conditional result was False 2025-10-26 22:45:21.217649 | localhost | skipping: Conditional result was False 2025-10-26 22:45:21.225058 | 2025-10-26 22:45:21.225289 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-26 22:45:21.271707 | localhost | skipping: Conditional result was False 2025-10-26 22:45:21.272408 | 2025-10-26 22:45:21.275571 | localhost | skipping: Conditional result was False 2025-10-26 22:45:21.287906 | 2025-10-26 22:45:21.288135 | LOOP [upload-logs : Upload console log and json output]