2025-10-24 08:24:04.305966 | Job console starting 2025-10-24 08:24:04.319124 | Updating git repos 2025-10-24 08:24:04.349870 | Cloning repos into workspace 2025-10-24 08:24:04.383223 | Restoring repo states 2025-10-24 08:24:04.387940 | Merging changes 2025-10-24 08:24:04.904080 | Checking out repos 2025-10-24 08:24:04.959019 | Preparing playbooks 2025-10-24 08:24:05.730695 | Running Ansible setup 2025-10-24 08:24:12.222311 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-24 08:24:13.255790 | 2025-10-24 08:24:13.255956 | PLAY [Base pre] 2025-10-24 08:24:13.275512 | 2025-10-24 08:24:13.275662 | TASK [Setup log path fact] 2025-10-24 08:24:13.305937 | noble | ok 2025-10-24 08:24:13.325453 | 2025-10-24 08:24:13.325611 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-24 08:24:13.380223 | noble | ok 2025-10-24 08:24:13.393524 | 2025-10-24 08:24:13.393654 | TASK [emit-job-header : Print job information] 2025-10-24 08:24:13.434471 | # Job Information 2025-10-24 08:24:13.434666 | Ansible Version: 2.16.14 2025-10-24 08:24:13.434702 | Job: openstack-ironic-images-build-osism-vyos 2025-10-24 08:24:13.434735 | Pipeline: check 2025-10-24 08:24:13.434759 | Executor: 521e9411259a 2025-10-24 08:24:13.434779 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/155 2025-10-24 08:24:13.434801 | Event ID: 60569680-b0b2-11f0-8a87-a5c0bb06cef0 2025-10-24 08:24:13.483274 | 2025-10-24 08:24:13.483490 | LOOP [emit-job-header : Print node information] 2025-10-24 08:24:13.660598 | noble | ok: 2025-10-24 08:24:13.665984 | noble | # Node Information 2025-10-24 08:24:13.666092 | noble | Inventory Hostname: noble 2025-10-24 08:24:13.666165 | noble | Hostname: ubuntu 2025-10-24 08:24:13.666192 | noble | Username: zuul 2025-10-24 08:24:13.666215 | noble | Distro: Ubuntu 24.04 2025-10-24 08:24:13.666240 | noble | Provider: regiocloud-a 2025-10-24 08:24:13.666262 | noble | Region: 2025-10-24 08:24:13.666285 | noble | Label: ubuntu-noble-uefi 2025-10-24 08:24:13.666306 | noble | Product Name: OpenStack Nova 2025-10-24 08:24:13.666326 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fea8:eedb 2025-10-24 08:24:13.682403 | 2025-10-24 08:24:13.682537 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-24 08:24:14.231030 | noble -> localhost | changed 2025-10-24 08:24:14.239284 | 2025-10-24 08:24:14.239411 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-24 08:24:15.522209 | noble -> localhost | changed 2025-10-24 08:24:15.544601 | 2025-10-24 08:24:15.544744 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-24 08:24:15.936251 | noble -> localhost | ok 2025-10-24 08:24:15.943518 | 2025-10-24 08:24:15.943644 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-24 08:24:15.972792 | noble | ok 2025-10-24 08:24:15.988907 | noble | included: /var/lib/zuul/builds/e58b44cb1d6e4217bd7bf7bcef86faca/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-24 08:24:16.002920 | 2025-10-24 08:24:16.003017 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-24 08:24:17.832488 | noble -> localhost | Generating public/private rsa key pair. 2025-10-24 08:24:17.832726 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/e58b44cb1d6e4217bd7bf7bcef86faca/work/e58b44cb1d6e4217bd7bf7bcef86faca_id_rsa 2025-10-24 08:24:17.832764 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/e58b44cb1d6e4217bd7bf7bcef86faca/work/e58b44cb1d6e4217bd7bf7bcef86faca_id_rsa.pub 2025-10-24 08:24:17.832790 | noble -> localhost | The key fingerprint is: 2025-10-24 08:24:17.832815 | noble -> localhost | SHA256:p9/L/PFphOidA3sa4IVtrSC4AkIrmGLJ3JsfCBy52IY zuul-build-sshkey 2025-10-24 08:24:17.832838 | noble -> localhost | The key's randomart image is: 2025-10-24 08:24:17.832859 | noble -> localhost | +---[RSA 3072]----+ 2025-10-24 08:24:17.832880 | noble -> localhost | | | 2025-10-24 08:24:17.832914 | noble -> localhost | | . | 2025-10-24 08:24:17.832935 | noble -> localhost | | + | 2025-10-24 08:24:17.832955 | noble -> localhost | |B+* . o . | 2025-10-24 08:24:17.832975 | noble -> localhost | |E@o. . .S+.+... | 2025-10-24 08:24:17.832997 | noble -> localhost | |=.o + . oo=o.. . | 2025-10-24 08:24:17.833018 | noble -> localhost | | = o ...o+ + | 2025-10-24 08:24:17.833037 | noble -> localhost | | o . . *.= +.| 2025-10-24 08:24:17.833058 | noble -> localhost | | . ..Bo+..| 2025-10-24 08:24:17.833079 | noble -> localhost | +----[SHA256]-----+ 2025-10-24 08:24:17.833167 | noble -> localhost | ok: Runtime: 0:00:01.359697 2025-10-24 08:24:17.840642 | 2025-10-24 08:24:17.840756 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-24 08:24:17.869634 | noble | ok 2025-10-24 08:24:17.879499 | noble | included: /var/lib/zuul/builds/e58b44cb1d6e4217bd7bf7bcef86faca/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-24 08:24:17.888615 | 2025-10-24 08:24:17.888712 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-24 08:24:17.902804 | noble | skipping: Conditional result was False 2025-10-24 08:24:17.910446 | 2025-10-24 08:24:17.910551 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-24 08:24:18.556941 | noble | changed 2025-10-24 08:24:18.563452 | 2025-10-24 08:24:18.563566 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-24 08:24:18.876987 | noble | ok 2025-10-24 08:24:18.886591 | 2025-10-24 08:24:18.886723 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-24 08:24:19.665523 | noble | changed 2025-10-24 08:24:19.672134 | 2025-10-24 08:24:19.672251 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-24 08:24:20.478048 | noble | changed 2025-10-24 08:24:20.486220 | 2025-10-24 08:24:20.486333 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-24 08:24:20.510169 | noble | skipping: Conditional result was False 2025-10-24 08:24:20.532423 | 2025-10-24 08:24:20.532545 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-24 08:24:20.950767 | noble -> localhost | changed 2025-10-24 08:24:20.965482 | 2025-10-24 08:24:20.965602 | TASK [add-build-sshkey : Add back temp key] 2025-10-24 08:24:21.361476 | noble -> localhost | Identity added: /var/lib/zuul/builds/e58b44cb1d6e4217bd7bf7bcef86faca/work/e58b44cb1d6e4217bd7bf7bcef86faca_id_rsa (zuul-build-sshkey) 2025-10-24 08:24:21.361982 | noble -> localhost | ok: Runtime: 0:00:00.023336 2025-10-24 08:24:21.378801 | 2025-10-24 08:24:21.379092 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-24 08:24:21.865349 | noble | ok 2025-10-24 08:24:21.871421 | 2025-10-24 08:24:21.871530 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-24 08:24:21.895344 | noble | skipping: Conditional result was False 2025-10-24 08:24:21.944056 | 2025-10-24 08:24:21.944194 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-24 08:24:22.388392 | noble | ok 2025-10-24 08:24:22.399572 | 2025-10-24 08:24:22.399685 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-24 08:24:22.428837 | noble | ok 2025-10-24 08:24:22.436136 | 2025-10-24 08:24:22.436239 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-24 08:24:22.703677 | noble -> localhost | ok 2025-10-24 08:24:22.711183 | 2025-10-24 08:24:22.711286 | TASK [validate-host : Collect information about the host] 2025-10-24 08:24:24.165688 | noble | ok 2025-10-24 08:24:24.190233 | 2025-10-24 08:24:24.190381 | TASK [validate-host : Sanitize hostname] 2025-10-24 08:24:24.261004 | noble | ok 2025-10-24 08:24:24.266616 | 2025-10-24 08:24:24.266725 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-24 08:24:24.837373 | noble -> localhost | changed 2025-10-24 08:24:24.843807 | 2025-10-24 08:24:24.843915 | TASK [validate-host : Collect information about zuul worker] 2025-10-24 08:24:25.382528 | noble | ok 2025-10-24 08:24:25.388363 | 2025-10-24 08:24:25.388485 | TASK [validate-host : Write out all zuul information for each host] 2025-10-24 08:24:25.913168 | noble -> localhost | changed 2025-10-24 08:24:25.925717 | 2025-10-24 08:24:25.925825 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-24 08:24:26.253294 | noble | ok 2025-10-24 08:24:26.263367 | 2025-10-24 08:24:26.263487 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-24 08:24:27.213705 | noble | changed: 2025-10-24 08:24:27.213925 | noble | cd+++++++++ src/ 2025-10-24 08:24:27.213960 | noble | cd+++++++++ src/github.com/ 2025-10-24 08:24:27.213985 | noble | cd+++++++++ src/github.com/osism/ 2025-10-24 08:24:27.214007 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-10-24 08:24:27.214028 | noble | localhost | ok: "/var/lib/zuul/builds/e58b44cb1d6e4217bd7bf7bcef86faca/work/logs" 2025-10-24 08:26:52.512769 | noble -> localhost | changed: "/var/lib/zuul/builds/e58b44cb1d6e4217bd7bf7bcef86faca/work/artifacts" 2025-10-24 08:26:52.783580 | noble -> localhost | changed: "/var/lib/zuul/builds/e58b44cb1d6e4217bd7bf7bcef86faca/work/docs" 2025-10-24 08:26:52.798784 | 2025-10-24 08:26:52.798932 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-24 08:26:54.087347 | noble | changed: 2025-10-24 08:26:54.087638 | noble | .d..t...... ./ 2025-10-24 08:26:54.087689 | noble | >f+++++++++ osism-vyos.log 2025-10-24 08:26:54.087760 | noble | changed: All items complete 2025-10-24 08:26:54.087803 | 2025-10-24 08:26:54.737467 | noble | changed: .d..t...... ./ 2025-10-24 08:26:55.393762 | noble | changed: .d..t...... ./ 2025-10-24 08:26:55.427430 | 2025-10-24 08:26:55.427580 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-24 08:26:55.937878 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.025653 2025-10-24 08:26:56.203242 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.009611 2025-10-24 08:26:56.222055 | 2025-10-24 08:26:56.222156 | PLAY RECAP 2025-10-24 08:26:56.222206 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-24 08:26:56.222232 | 2025-10-24 08:26:56.336468 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-10-24 08:26:56.337386 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-24 08:26:57.073435 | 2025-10-24 08:26:57.074366 | PLAY [Base post] 2025-10-24 08:26:57.089172 | 2025-10-24 08:26:57.089294 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-24 08:26:57.717917 | noble | changed 2025-10-24 08:26:57.728420 | 2025-10-24 08:26:57.728534 | PLAY RECAP 2025-10-24 08:26:57.728608 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-24 08:26:57.728684 | 2025-10-24 08:26:57.840738 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-24 08:26:57.841680 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-24 08:26:58.586322 | 2025-10-24 08:26:58.586481 | PLAY [Base post-logs] 2025-10-24 08:26:58.596795 | 2025-10-24 08:26:58.596921 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-24 08:26:59.068517 | localhost | changed 2025-10-24 08:26:59.080325 | 2025-10-24 08:26:59.080487 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-24 08:26:59.116394 | localhost | ok 2025-10-24 08:26:59.119892 | 2025-10-24 08:26:59.120001 | TASK [Set zuul-log-path fact] 2025-10-24 08:26:59.145901 | localhost | ok 2025-10-24 08:26:59.158262 | 2025-10-24 08:26:59.158417 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-24 08:26:59.194785 | localhost | ok 2025-10-24 08:26:59.199450 | 2025-10-24 08:26:59.199592 | TASK [upload-logs : Create log directories] 2025-10-24 08:26:59.663581 | localhost | changed 2025-10-24 08:26:59.666418 | 2025-10-24 08:26:59.666524 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-24 08:27:00.145184 | localhost -> localhost | ok: Runtime: 0:00:00.007057 2025-10-24 08:27:00.150212 | 2025-10-24 08:27:00.150347 | TASK [upload-logs : Upload logs to log server] 2025-10-24 08:27:00.726232 | localhost | Output suppressed because no_log was given 2025-10-24 08:27:00.728506 | 2025-10-24 08:27:00.728638 | LOOP [upload-logs : Compress console log and json output] 2025-10-24 08:27:00.778014 | localhost | skipping: Conditional result was False 2025-10-24 08:27:00.783008 | localhost | skipping: Conditional result was False 2025-10-24 08:27:00.790106 | 2025-10-24 08:27:00.790321 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-24 08:27:00.834365 | localhost | skipping: Conditional result was False 2025-10-24 08:27:00.834997 | 2025-10-24 08:27:00.838781 | localhost | skipping: Conditional result was False 2025-10-24 08:27:00.850530 | 2025-10-24 08:27:00.850733 | LOOP [upload-logs : Upload console log and json output]