2025-04-05 08:30:54.865032 | Job console starting... 2025-04-05 08:30:54.873084 | Updating repositories 2025-04-05 08:30:54.881831 | Preparing job workspace 2025-04-05 08:30:55.650539 | Running Ansible setup... 2025-04-05 08:31:00.529624 | PRE-RUN START: [trusted : github.com/regiocloud/zuul-config/playbooks/base/pre.yaml@main] 2025-04-05 08:31:01.222840 | 2025-04-05 08:31:01.222995 | PLAY [Base pre] 2025-04-05 08:31:01.252297 | 2025-04-05 08:31:01.252476 | TASK [Setup log path fact] 2025-04-05 08:31:01.284741 | ubuntu-jammy | ok 2025-04-05 08:31:01.303408 | 2025-04-05 08:31:01.303549 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-04-05 08:31:01.340475 | ubuntu-jammy | ok 2025-04-05 08:31:01.365676 | 2025-04-05 08:31:01.365805 | TASK [emit-job-header : Print job information] 2025-04-05 08:31:01.435974 | # Job Information 2025-04-05 08:31:01.436281 | Ansible Version: 2.15.3 2025-04-05 08:31:01.436343 | Job: e2e-tests-gardener-1.31-quick 2025-04-05 08:31:01.436426 | Pipeline: periodic-hourly 2025-04-05 08:31:01.436468 | Executor: 7d211f194f6a 2025-04-05 08:31:01.436501 | Triggered by: https://github.com/regiocloud/e2e-tests 2025-04-05 08:31:01.436533 | Event ID: eda95092e5a54e42b2c715c4576d617b 2025-04-05 08:31:01.445677 | 2025-04-05 08:31:01.445808 | LOOP [emit-job-header : Print node information] 2025-04-05 08:31:01.592842 | ubuntu-jammy | ok: 2025-04-05 08:31:01.593078 | ubuntu-jammy | # Node Information 2025-04-05 08:31:01.593142 | ubuntu-jammy | Inventory Hostname: ubuntu-jammy 2025-04-05 08:31:01.593180 | ubuntu-jammy | Hostname: ubuntu 2025-04-05 08:31:01.593208 | ubuntu-jammy | Username: zuul 2025-04-05 08:31:01.593235 | ubuntu-jammy | Distro: Ubuntu 22.04 2025-04-05 08:31:01.593266 | ubuntu-jammy | Provider: regiocloud-a 2025-04-05 08:31:01.593292 | ubuntu-jammy | Label: ubuntu-jammy 2025-04-05 08:31:01.593319 | ubuntu-jammy | Product Name: OpenStack Nova 2025-04-05 08:31:01.593345 | ubuntu-jammy | Interface IP: 81.163.192.189 2025-04-05 08:31:01.620558 | 2025-04-05 08:31:01.620707 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-04-05 08:31:02.136358 | ubuntu-jammy -> localhost | changed 2025-04-05 08:31:02.145370 | 2025-04-05 08:31:02.145492 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-04-05 08:31:03.199981 | ubuntu-jammy -> localhost | changed 2025-04-05 08:31:03.215313 | 2025-04-05 08:31:03.215437 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-04-05 08:31:03.496132 | ubuntu-jammy -> localhost | ok 2025-04-05 08:31:03.512892 | 2025-04-05 08:31:03.513078 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-04-05 08:31:03.552825 | ubuntu-jammy | ok 2025-04-05 08:31:03.573190 | ubuntu-jammy | included: /var/lib/zuul/builds/9a6c6f2f4ecb4efb96d2ab20e802fb93/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-04-05 08:31:03.581085 | 2025-04-05 08:31:03.581205 | TASK [add-build-sshkey : Create Temp SSH key] 2025-04-05 08:31:04.118447 | ubuntu-jammy -> localhost | Generating public/private rsa key pair. 2025-04-05 08:31:04.118924 | ubuntu-jammy -> localhost | Your identification has been saved in /var/lib/zuul/builds/9a6c6f2f4ecb4efb96d2ab20e802fb93/work/9a6c6f2f4ecb4efb96d2ab20e802fb93_id_rsa 2025-04-05 08:31:04.119023 | ubuntu-jammy -> localhost | Your public key has been saved in /var/lib/zuul/builds/9a6c6f2f4ecb4efb96d2ab20e802fb93/work/9a6c6f2f4ecb4efb96d2ab20e802fb93_id_rsa.pub 2025-04-05 08:31:04.119093 | ubuntu-jammy -> localhost | The key fingerprint is: 2025-04-05 08:31:04.119179 | ubuntu-jammy -> localhost | SHA256:Dhi8KsdkE+0igyOCXhuNVMnaiRSetPZphQiwT76Uduc zuul-build-sshkey 2025-04-05 08:31:04.119241 | ubuntu-jammy -> localhost | The key's randomart image is: 2025-04-05 08:31:04.119300 | ubuntu-jammy -> localhost | +---[RSA 3072]----+ 2025-04-05 08:31:04.119358 | ubuntu-jammy -> localhost | |o. oo.. | 2025-04-05 08:31:04.119413 | ubuntu-jammy -> localhost | | .++=o. | 2025-04-05 08:31:04.119468 | ubuntu-jammy -> localhost | |. +BB... | 2025-04-05 08:31:04.119543 | ubuntu-jammy -> localhost | |o+o*+*o | 2025-04-05 08:31:04.119601 | ubuntu-jammy -> localhost | |O @+==o S | 2025-04-05 08:31:04.119656 | ubuntu-jammy -> localhost | |+X.*+o o | 2025-04-05 08:31:04.119725 | ubuntu-jammy -> localhost | |..=. E . | 2025-04-05 08:31:04.119783 | ubuntu-jammy -> localhost | | o | 2025-04-05 08:31:04.119837 | ubuntu-jammy -> localhost | | | 2025-04-05 08:31:04.119892 | ubuntu-jammy -> localhost | +----[SHA256]-----+ 2025-04-05 08:31:04.120023 | ubuntu-jammy -> localhost | ok: Runtime: 0:00:00.049092 2025-04-05 08:31:04.138663 | 2025-04-05 08:31:04.138818 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-04-05 08:31:04.175484 | ubuntu-jammy | ok 2025-04-05 08:31:04.189137 | ubuntu-jammy | included: /var/lib/zuul/builds/9a6c6f2f4ecb4efb96d2ab20e802fb93/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-04-05 08:31:04.198777 | 2025-04-05 08:31:04.198875 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-04-05 08:31:04.224711 | ubuntu-jammy | skipping: Conditional result was False 2025-04-05 08:31:04.233552 | 2025-04-05 08:31:04.233665 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-04-05 08:31:04.879729 | ubuntu-jammy | changed 2025-04-05 08:31:04.893370 | 2025-04-05 08:31:04.893502 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-04-05 08:31:05.168065 | ubuntu-jammy | ok 2025-04-05 08:31:05.178171 | 2025-04-05 08:31:05.178303 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-04-05 08:31:05.781152 | ubuntu-jammy | changed 2025-04-05 08:31:05.790919 | 2025-04-05 08:31:05.791054 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-04-05 08:31:06.377417 | ubuntu-jammy | changed 2025-04-05 08:31:06.387998 | 2025-04-05 08:31:06.388146 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-04-05 08:31:06.423546 | ubuntu-jammy | skipping: Conditional result was False 2025-04-05 08:31:06.439011 | 2025-04-05 08:31:06.439212 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-04-05 08:31:06.899891 | ubuntu-jammy -> localhost | changed 2025-04-05 08:31:06.924865 | 2025-04-05 08:31:06.925008 | TASK [add-build-sshkey : Add back temp key] 2025-04-05 08:31:07.289513 | ubuntu-jammy -> localhost | Identity added: /var/lib/zuul/builds/9a6c6f2f4ecb4efb96d2ab20e802fb93/work/9a6c6f2f4ecb4efb96d2ab20e802fb93_id_rsa (zuul-build-sshkey) 2025-04-05 08:31:07.289961 | ubuntu-jammy -> localhost | ok: Runtime: 0:00:00.020201 2025-04-05 08:31:07.304412 | 2025-04-05 08:31:07.304554 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-04-05 08:31:07.730683 | ubuntu-jammy | ok 2025-04-05 08:31:07.738942 | 2025-04-05 08:31:07.739060 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-04-05 08:31:07.773876 | ubuntu-jammy | skipping: Conditional result was False 2025-04-05 08:31:07.796824 | 2025-04-05 08:31:07.796953 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-04-05 08:31:08.258470 | ubuntu-jammy | ok 2025-04-05 08:31:08.304714 | 2025-04-05 08:31:08.304840 | TASK [validate-host : Define zuul_info_dir fact] 2025-04-05 08:31:08.347711 | ubuntu-jammy | ok 2025-04-05 08:31:08.354676 | 2025-04-05 08:31:08.354782 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-04-05 08:31:08.651013 | ubuntu-jammy -> localhost | ok 2025-04-05 08:31:08.670375 | 2025-04-05 08:31:08.670498 | TASK [validate-host : Collect information about the host] 2025-04-05 08:31:09.890948 | ubuntu-jammy | ok 2025-04-05 08:31:09.904090 | 2025-04-05 08:31:09.904227 | TASK [validate-host : Sanitize hostname] 2025-04-05 08:31:09.985889 | ubuntu-jammy | ok 2025-04-05 08:31:09.995876 | 2025-04-05 08:31:09.996008 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-04-05 08:31:10.576217 | ubuntu-jammy -> localhost | changed 2025-04-05 08:31:10.591565 | 2025-04-05 08:31:10.591723 | TASK [validate-host : Collect information about zuul worker] 2025-04-05 08:31:11.060010 | ubuntu-jammy | ok 2025-04-05 08:31:11.070778 | 2025-04-05 08:31:11.070907 | TASK [validate-host : Write out all zuul information for each host] 2025-04-05 08:31:11.637463 | ubuntu-jammy -> localhost | changed 2025-04-05 08:31:11.654883 | 2025-04-05 08:31:11.655010 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-04-05 08:31:11.967706 | ubuntu-jammy | ok 2025-04-05 08:31:11.976921 | 2025-04-05 08:31:11.977044 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-04-05 08:31:12.780210 | ubuntu-jammy | changed: 2025-04-05 08:31:12.780540 | ubuntu-jammy | cd+++++++++ src/ 2025-04-05 08:31:12.780606 | ubuntu-jammy | cd+++++++++ src/github.com/ 2025-04-05 08:31:12.780649 | ubuntu-jammy | cd+++++++++ src/github.com/regiocloud/ 2025-04-05 08:31:12.780686 | ubuntu-jammy | cd+++++++++ src/github.com/regiocloud/e2e-tests/ 2025-04-05 08:31:12.780721 | ubuntu-jammy | 6m41s v1.31.7 2025-04-05 08:42:08.105518 | ubuntu-jammy | shoot--cruv4yqhsc--9a6c6f2f-worker-hktkk-z1-67d55-gw5cc Ready 6m38s v1.31.7 2025-04-05 08:42:08.105595 | ubuntu-jammy | shoot--cruv4yqhsc--9a6c6f2f-worker-hktkk-z1-67d55-k87gr Ready 6m38s v1.31.7 2025-04-05 08:42:08.105673 | ubuntu-jammy | shoot--cruv4yqhsc--9a6c6f2f-worker-hktkk-z1-67d55-nzq9z Ready 6m41s v1.31.7 2025-04-05 08:42:08.482015 | ubuntu-jammy | ok 2025-04-05 08:42:08.515272 | 2025-04-05 08:42:08.515343 | PLAY RECAP 2025-04-05 08:42:08.515394 | ubuntu-jammy | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-04-05 08:42:08.515420 | 2025-04-05 08:42:08.594851 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/regiocloud/e2e-tests/playbooks/gardener/pre.yml@main] 2025-04-05 08:42:08.602027 | RUN START: [untrusted : github.com/regiocloud/e2e-tests/playbooks/gardener/run.yml@main] 2025-04-05 08:42:09.207347 | 2025-04-05 08:42:09.207499 | PLAY [Run play] 2025-04-05 08:42:09.234761 | 2025-04-05 08:42:09.234918 | TASK [Run cilium script] 2025-04-05 08:42:09.342732 | ubuntu-jammy | skipping: Conditional result was False 2025-04-05 08:42:09.357558 | 2025-04-05 08:42:09.357745 | TASK [Run sonobuoy script] 2025-04-05 08:42:09.888222 | ubuntu-jammy | + export KUBECONFIG=kubeconfig.yaml 2025-04-05 08:42:10.182240 | ubuntu-jammy | + KUBECONFIG=kubeconfig.yaml 2025-04-05 08:42:10.182337 | ubuntu-jammy | + '[' -n '' ']' 2025-04-05 08:42:10.182352 | ubuntu-jammy | + '[' -n '' ']' 2025-04-05 08:42:10.182364 | ubuntu-jammy | ++ uuidgen 2025-04-05 08:42:10.182373 | ubuntu-jammy | + export GCTL_SESSION_ID=02e3631f-9566-4e5b-8777-0e36f5ce107d 2025-04-05 08:42:10.182385 | ubuntu-jammy | + GCTL_SESSION_ID=02e3631f-9566-4e5b-8777-0e36f5ce107d 2025-04-05 08:42:10.182394 | ubuntu-jammy | + gardenctl target --garden e2e --project cruv4yqhsc --shoot 9a6c6f2f 2025-04-05 08:42:10.182429 | ubuntu-jammy | Successfully targeted shoot "9a6c6f2f" 2025-04-05 08:42:10.185223 | ubuntu-jammy | WARN The KUBECONFIG environment variable does not point to the current target of gardenctl. Run `gardenctl kubectl-env --help` on how to configure the KUBECONFIG environment variable accordingly 2025-04-05 08:42:10.185308 | ubuntu-jammy | ++ gardenctl kubectl-env bash 2025-04-05 08:42:10.262883 | ubuntu-jammy | + eval export 'KUBECONFIG='\''/tmp/garden/sessions/02e3631f-9566-4e5b-8777-0e36f5ce107d/kubeconfig.yaml'\'';' '#' Run this command to configure kubectl for your shell: '#' eval '$(gardenctl' kubectl-env 'bash)' 2025-04-05 08:42:10.262971 | ubuntu-jammy | ++ export KUBECONFIG=/tmp/garden/sessions/02e3631f-9566-4e5b-8777-0e36f5ce107d/kubeconfig.yaml 2025-04-05 08:42:10.262987 | ubuntu-jammy | ++ KUBECONFIG=/tmp/garden/sessions/02e3631f-9566-4e5b-8777-0e36f5ce107d/kubeconfig.yaml 2025-04-05 08:42:10.263022 | ubuntu-jammy | + sonobuoy delete --wait 2025-04-05 08:42:10.410875 | ubuntu-jammy | time="2025-04-05T08:42:10Z" level=info msg="already deleted" dry-run=false kind=namespace namespace=sonobuoy 2025-04-05 08:42:10.429287 | ubuntu-jammy | time="2025-04-05T08:42:10Z" level=info msg="delete request issued" dry-run=false kind=clusterrolebindings names="[]" 2025-04-05 08:42:10.446071 | ubuntu-jammy | time="2025-04-05T08:42:10Z" level=info msg="delete request issued" dry-run=false kind=clusterroles names="[]" 2025-04-05 08:42:25.524971 | ubuntu-jammy | 2025-04-05 08:42:25.526767 | ubuntu-jammy | Error encountered while waiting for namespace deletion "": Get "https://api.9a6c6f2f.cruv4yqhsc.okeanos.tech/api/v1/namespaces/sonobuoy": EOF 2025-04-05 08:42:25.526831 | ubuntu-jammy | time="2025-04-05T08:42:25Z" level=error msg="failed to delete sonobuoy resources: waiting for delete conditions to be met: Get \"https://api.9a6c6f2f.cruv4yqhsc.okeanos.tech/api/v1/namespaces/sonobuoy\": EOF" 2025-04-05 08:42:35.826942 | ubuntu-jammy | ERROR 2025-04-05 08:42:35.827193 | ubuntu-jammy | { 2025-04-05 08:42:35.827233 | ubuntu-jammy | "delta": "0:00:15.650059", 2025-04-05 08:42:35.827260 | ubuntu-jammy | "end": "2025-04-05 08:42:25.527213", 2025-04-05 08:42:35.827283 | ubuntu-jammy | "msg": "non-zero return code", 2025-04-05 08:42:35.827303 | ubuntu-jammy | "rc": 1, 2025-04-05 08:42:35.827327 | ubuntu-jammy | "start": "2025-04-05 08:42:09.877154" 2025-04-05 08:42:35.827351 | ubuntu-jammy | } failure 2025-04-05 08:42:35.829931 | 2025-04-05 08:42:35.830035 | PLAY RECAP 2025-04-05 08:42:35.830099 | ubuntu-jammy | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0 2025-04-05 08:42:35.830133 | 2025-04-05 08:42:35.939314 | RUN END RESULT_NORMAL: [untrusted : github.com/regiocloud/e2e-tests/playbooks/gardener/run.yml@main] 2025-04-05 08:42:35.942219 | POST-RUN START: [untrusted : github.com/regiocloud/e2e-tests/playbooks/gardener/post.yml@main] 2025-04-05 08:42:36.660465 | 2025-04-05 08:42:36.660618 | PLAY [Post play] 2025-04-05 08:42:36.687745 | 2025-04-05 08:42:36.687896 | TASK [Run post script] 2025-04-05 08:42:37.204201 | ubuntu-jammy | + export KUBECONFIG=kubeconfig.yaml 2025-04-05 08:42:37.478293 | ubuntu-jammy | + KUBECONFIG=kubeconfig.yaml 2025-04-05 08:42:37.478394 | ubuntu-jammy | + kubectl annotate confirmation.gardener.cloud/deletion=true -f deployment.yaml 2025-04-05 08:42:37.478437 | ubuntu-jammy | shoot.core.gardener.cloud/9a6c6f2f annotated 2025-04-05 08:42:37.481202 | ubuntu-jammy | + kubectl delete -f deployment.yaml 2025-04-05 08:42:37.682148 | ubuntu-jammy | shoot.core.gardener.cloud "9a6c6f2f" deleted 2025-04-05 08:46:31.571307 | ubuntu-jammy | ok 2025-04-05 08:46:31.612720 | 2025-04-05 08:46:31.612793 | PLAY RECAP 2025-04-05 08:46:31.612843 | ubuntu-jammy | ok: 1 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-04-05 08:46:31.612869 | 2025-04-05 08:46:31.685115 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/regiocloud/e2e-tests/playbooks/gardener/post.yml@main] 2025-04-05 08:46:31.687848 | POST-RUN START: [trusted : github.com/regiocloud/zuul-config/playbooks/base/post.yaml@main] 2025-04-05 08:46:32.303730 | 2025-04-05 08:46:32.303854 | PLAY [Base post] 2025-04-05 08:46:32.329345 | 2025-04-05 08:46:32.329457 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-04-05 08:46:32.951450 | ubuntu-jammy | changed 2025-04-05 08:46:32.984982 | 2025-04-05 08:46:32.985096 | PLAY RECAP 2025-04-05 08:46:32.985162 | ubuntu-jammy | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-04-05 08:46:32.985225 | 2025-04-05 08:46:33.091620 | POST-RUN END RESULT_NORMAL: [trusted : github.com/regiocloud/zuul-config/playbooks/base/post.yaml@main] 2025-04-05 08:46:33.098956 | POST-RUN START: [trusted : github.com/regiocloud/zuul-config/playbooks/base/post-logs.yaml@main] 2025-04-05 08:46:33.828698 | 2025-04-05 08:46:33.828848 | PLAY [Base post-logs] 2025-04-05 08:46:33.844951 | 2025-04-05 08:46:33.845093 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-04-05 08:46:34.282570 | localhost | changed 2025-04-05 08:46:34.286564 | 2025-04-05 08:46:34.286699 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-04-05 08:46:34.324779 | localhost | ok 2025-04-05 08:46:34.331695 | 2025-04-05 08:46:34.331808 | TASK [Set zuul-log-path fact] 2025-04-05 08:46:34.349741 | localhost | ok 2025-04-05 08:46:34.361030 | 2025-04-05 08:46:34.361138 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-04-05 08:46:34.388224 | localhost | ok 2025-04-05 08:46:34.393725 | 2025-04-05 08:46:34.393826 | TASK [upload-logs : Create log directories] 2025-04-05 08:46:34.937196 | localhost | changed 2025-04-05 08:46:34.944700 | 2025-04-05 08:46:34.944836 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-04-05 08:46:35.440974 | localhost -> localhost | ok: Runtime: 0:00:00.008212 2025-04-05 08:46:35.451952 | 2025-04-05 08:46:35.452113 | TASK [upload-logs : Upload logs to log server] 2025-04-05 08:46:35.968713 | localhost | Output suppressed because no_log was given 2025-04-05 08:46:35.971569 | 2025-04-05 08:46:35.971656 | LOOP [upload-logs : Compress console log and json output] 2025-04-05 08:46:36.041499 | localhost | skipping: Conditional result was False 2025-04-05 08:46:36.057312 | localhost | skipping: Conditional result was False 2025-04-05 08:46:36.073972 | 2025-04-05 08:46:36.074149 | LOOP [upload-logs : Upload compressed console log and json output] 2025-04-05 08:46:36.129698 | localhost | skipping: Conditional result was False 2025-04-05 08:46:36.130333 | 2025-04-05 08:46:36.141662 | localhost | skipping: Conditional result was False 2025-04-05 08:46:36.151907 | 2025-04-05 08:46:36.152103 | LOOP [upload-logs : Upload console log and json output]