kata logs

gistfile1.txt

Apr 13 06:56:24 scw-kata-21-default-92494fc088b4493b92bc447331 kata[152811]: time="2021-04-13T06:56:24.43265446Z" level=info msg="read from QMP: {\"QMP\": {\"version\": {\"qemu\": {\"micro\": 0, \"minor\": 2, \"major\": 5}, \"package\": \"kata-static\"}, \"capabilities\": [\"oob\"]}}" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qmp
Apr 13 06:56:24 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:24.432654460Z" level=info msg="read from QMP: {\"QMP\": {\"version\": {\"qemu\": {\"micro\": 0, \"minor\": 2, \"major\": 5}, \"package\": \"kata-static\"}, \"capabilities\": [\"oob\"]}}" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qmp
Apr 13 06:56:24 scw-kata-21-default-92494fc088b4493b92bc447331 kata[152811]: time="2021-04-13T06:56:24.433541983Z" level=info msg="QMP details" name=containerd-shim-v2 pid=152811 qmp-capabilities=oob qmp-major-version=5 qmp-micro-version=0 qmp-minor-version=2 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qemu
Apr 13 06:56:24 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:24.433541983Z" level=info msg="QMP details" name=containerd-shim-v2 pid=152811 qmp-capabilities=oob qmp-major-version=5 qmp-micro-version=0 qmp-minor-version=2 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qemu
Apr 13 06:56:24 scw-kata-21-default-92494fc088b4493b92bc447331 kata[152811]: time="2021-04-13T06:56:24.43383185Z" level=info msg="{\"execute\":\"qmp_capabilities\"}" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qmp
Apr 13 06:56:24 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:24.433831850Z" level=info msg="{\"execute\":\"qmp_capabilities\"}" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qmp
Apr 13 06:56:24 scw-kata-21-default-92494fc088b4493b92bc447331 kata[152811]: time="2021-04-13T06:56:24.434452773Z" level=info msg="read from QMP: {\"return\": {}}" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qmp
Apr 13 06:56:24 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:24.434452773Z" level=info msg="read from QMP: {\"return\": {}}" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qmp
Apr 13 06:56:24 scw-kata-21-default-92494fc088b4493b92bc447331 kata[152811]: time="2021-04-13T06:56:24.434874247Z" level=info msg="scanner return error: read unix @->/run/vc/vm/9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704/qmp.sock: use of closed network connection" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qmp
Apr 13 06:56:24 scw-kata-21-default-92494fc088b4493b92bc447331 kata[152811]: time="2021-04-13T06:56:24.43500837Z" level=info msg="VM started" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=sandbox
Apr 13 06:56:24 scw-kata-21-default-92494fc088b4493b92bc447331 kata[152811]: time="2021-04-13T06:56:24.435190734Z" level=debug msg="console watcher starts" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=sandbox
Apr 13 06:56:24 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:24.434874247Z" level=info msg="scanner return error: read unix @->/run/vc/vm/9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704/qmp.sock: use of closed network connection" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qmp
Apr 13 06:56:24 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:24.435008370Z" level=info msg="VM started" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=sandbox
Apr 13 06:56:24 scw-kata-21-default-92494fc088b4493b92bc447331 kata[152811]: time="2021-04-13T06:56:24.435438051Z" level=info msg="New client" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=kata_agent url="vsock://313364734:1024"
Apr 13 06:56:24 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:24.435190734Z" level=debug msg="console watcher starts" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=sandbox
Apr 13 06:56:24 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:24.435438051Z" level=info msg="New client" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=kata_agent url="vsock://313364734:1024"
Apr 13 06:56:24 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:24.625637002Z" level=debug msg="garbage collected" d=343.022355ms
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Got VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Waiting for VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Got VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Waiting for VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Got VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Waiting for VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Got VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Waiting for VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Got VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Waiting for VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Got VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Waiting for VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Got VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Waiting for VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Got VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Waiting for VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Got VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] fv_queue_set_started: qidx=0 started=1
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Waiting for VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Got VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Waiting for VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Got VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Waiting for VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Got VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Waiting for VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Got VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] fv_queue_set_started: qidx=1 started=1
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000003] fv_queue_thread: Start for queue 0 kick_fd 9
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Waiting for VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Got VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000003] fv_queue_thread: Waiting for Queue 0 event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000003] fv_queue_thread: Got queue event on Queue 0
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Waiting for VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Got VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000004] fv_queue_thread: Start for queue 1 kick_fd 12
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000004] fv_queue_thread: Waiting for Queue 1 event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000004] fv_queue_thread: Got queue event on Queue 1
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000003] fv_queue_thread: Queue 0 gave evalue: 1 available: in: 0 out: 0
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000003] fv_queue_thread: Waiting for Queue 0 event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000004] fv_queue_thread: Queue 1 gave evalue: 1 available: in: 0 out: 0
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Waiting for VU event
Apr 13 06:56:25 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000004] fv_queue_thread: Waiting for Queue 1 event
Apr 13 06:56:27 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:27.203433926Z" level=debug msg=ImageFsInfo
Apr 13 06:56:27 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:27.203575504Z" level=debug msg="ImageFsInfo returns filesystem info [&FilesystemUsage{Timestamp:1618296985766211841,FsId:&FilesystemIdentifier{Mountpoint:/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs,},UsedBytes:&UInt64Value{Value:1282076672,},InodesUsed:&UInt64Value{Value:22278,},}]"
Apr 13 06:56:37 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:37.303350553Z" level=debug msg=ImageFsInfo
Apr 13 06:56:37 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:37.303474556Z" level=debug msg="ImageFsInfo returns filesystem info [&FilesystemUsage{Timestamp:1618296995763390468,FsId:&FilesystemIdentifier{Mountpoint:/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs,},UsedBytes:&UInt64Value{Value:1282076672,},InodesUsed:&UInt64Value{Value:22278,},}]"
Apr 13 06:56:47 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:47.402989406Z" level=debug msg=ImageFsInfo
Apr 13 06:56:47 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:47.403130542Z" level=debug msg="ImageFsInfo returns filesystem info [&FilesystemUsage{Timestamp:1618297005762859086,FsId:&FilesystemIdentifier{Mountpoint:/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs,},UsedBytes:&UInt64Value{Value:1282076672,},InodesUsed:&UInt64Value{Value:22278,},}]"
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 kata[152811]: time="2021-04-13T06:56:54.686417001Z" level=info msg="Stopping Sandbox" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qemu
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:54.686417001Z" level=info msg="Stopping Sandbox" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qemu
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 kata[152811]: time="2021-04-13T06:56:54.68724288Z" level=info msg="read from QMP: {\"QMP\": {\"version\": {\"qemu\": {\"micro\": 0, \"minor\": 2, \"major\": 5}, \"package\": \"kata-static\"}, \"capabilities\": [\"oob\"]}}" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qmp
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 kata[152811]: time="2021-04-13T06:56:54.687939522Z" level=info msg="{\"execute\":\"qmp_capabilities\"}" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qmp
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 kata[152811]: time="2021-04-13T06:56:54.696152055Z" level=info msg="read from QMP: {\"return\": {}}" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qmp
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 kata[152811]: time="2021-04-13T06:56:54.696769058Z" level=info msg="{\"execute\":\"quit\"}" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qmp
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:54.687242880Z" level=info msg="read from QMP: {\"QMP\": {\"version\": {\"qemu\": {\"micro\": 0, \"minor\": 2, \"major\": 5}, \"package\": \"kata-static\"}, \"capabilities\": [\"oob\"]}}" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qmp
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:54.687939522Z" level=info msg="{\"execute\":\"qmp_capabilities\"}" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qmp
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:54.696152055Z" level=info msg="read from QMP: {\"return\": {}}" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qmp
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:54.696769058Z" level=info msg="{\"execute\":\"quit\"}" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qmp
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 kata[152811]: time="2021-04-13T06:56:54.698568213Z" level=info msg="read from QMP: {\"return\": {}}" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qmp
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Got VU event
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] fv_queue_set_started: qidx=0 started=0
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000003] fv_queue_thread: kill event on queue 0 - quitting
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 kata[152811]: time="2021-04-13T06:56:54.699159167Z" level=info msg="read from QMP: {\"timestamp\": {\"seconds\": 1618297014, \"microseconds\": 698146}, \"event\": \"SHUTDOWN\", \"data\": {\"guest\": false, \"reason\": \"host-qmp-quit\"}}" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qmp
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] fv_remove_watch: TODO! fd=9
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Waiting for VU event
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 kata[152811]: time="2021-04-13T06:56:54.699801429Z" level=debug msg="got QMP event" event="{SHUTDOWN map[guest:false reason:host-qmp-quit] 2021-04-13 06:56:54.000698146 +0000 UTC}" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qemu
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 kata[152811]: time="2021-04-13T06:56:54.700532437Z" level=info msg="cleanup vm path" dir=/run/vc/vm/9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 link=/run/vc/vm/9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qemu
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 kata[152811]: time="2021-04-13T06:56:54.701232739Z" level=info msg="Detaching endpoint" endpoint-type=virtual name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=network
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 kata[152811]: time="2021-04-13T06:56:54.701543796Z" level=debug msg="Network removed" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=network
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 kata[152811]: time="2021-04-13T06:56:54.701687827Z" level=debug msg="Deleting sandbox cgroup" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=sandbox
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:54.698568213Z" level=info msg="read from QMP: {\"return\": {}}" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qmp
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:54.699159167Z" level=info msg="read from QMP: {\"timestamp\": {\"seconds\": 1618297014, \"microseconds\": 698146}, \"event\": \"SHUTDOWN\", \"data\": {\"guest\": false, \"reason\": \"host-qmp-quit\"}}" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qmp
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:54.699801429Z" level=debug msg="got QMP event" event="{SHUTDOWN map[guest:false reason:host-qmp-quit] 2021-04-13 06:56:54.000698146 +0000 UTC}" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qemu
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:54.700532437Z" level=info msg="cleanup vm path" dir=/run/vc/vm/9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 link=/run/vc/vm/9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=qemu
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:54.701232739Z" level=info msg="Detaching endpoint" endpoint-type=virtual name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=network
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:54.701543796Z" level=debug msg="Network removed" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=network
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Got VU event
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] fv_queue_set_started: qidx=1 started=0
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000004] fv_queue_thread: kill event on queue 1 - quitting
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] fv_remove_watch: TODO! fd=12
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Waiting for VU event
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:54.701687827Z" level=debug msg="Deleting sandbox cgroup" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=sandbox
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:54.704343496Z" level=warning msg="sandbox cgroups path is empty" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=sandbox
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 kata[152811]: time="2021-04-13T06:56:54.704343496Z" level=warning msg="sandbox cgroups path is empty" name=containerd-shim-v2 pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=sandbox
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:54.704491686Z" level=info msg="cleanup agent" name=containerd-shim-v2 path=/run/kata-containers/shared/sandboxes/9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704/shared pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=kata_agent
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 kata[152811]: time="2021-04-13T06:56:54.704491686Z" level=info msg="cleanup agent" name=containerd-shim-v2 path=/run/kata-containers/shared/sandboxes/9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704/shared pid=152811 sandbox=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704 source=virtcontainers subsystem=kata_agent
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 systemd[1]: run-kata\x2dcontainers-shared-sandboxes-9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704-shared.mount: Succeeded.
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 systemd[151473]: run-kata\x2dcontainers-shared-sandboxes-9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704-shared.mount: Succeeded.
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Got VU event
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Waiting for VU event
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Got VU event
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Waiting for VU event
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:54.709770775Z" level=debug msg="failed to delete task" error="rpc error: code = NotFound desc = container does not exist 9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704: not found" id=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:54.714556001Z" level=info msg="shim disconnected" id=9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:54.714710442Z" level=error msg="copy shim log" error="read /proc/self/fd/93: file already closed"
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 systemd[151473]: run-containerd-io.containerd.runtime.v2.task-k8s.io-9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704-rootfs.mount: Succeeded.
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 systemd[1]: run-containerd-io.containerd.runtime.v2.task-k8s.io-9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704-rootfs.mount: Succeeded.
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Unexpected poll revents 11
Apr 13 06:56:54 scw-kata-21-default-92494fc088b4493b92bc447331 virtiofsd[152829]: [ID: 00000001] virtio_loop: Exit
Apr 13 06:56:55 scw-kata-21-default-92494fc088b4493b92bc447331 systemd[151473]: run-containerd-io.containerd.grpc.v1.cri-sandboxes-9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704-shm.mount: Succeeded.
Apr 13 06:56:55 scw-kata-21-default-92494fc088b4493b92bc447331 systemd[1]: run-containerd-io.containerd.grpc.v1.cri-sandboxes-9218960f55bd40991150833f2c4ef3277285c8a5482f5f6ad57766b928a9d704-shm.mount: Succeeded.
Apr 13 06:56:55 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:55.891710265Z" level=debug msg="event published" ns=k8s.io topic=/snapshot/remove type=containerd.events.SnapshotRemove
Apr 13 06:56:55 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:55.983614370Z" level=debug msg="event published" ns=k8s.io topic=/containers/delete type=containerd.events.ContainerDelete
Apr 13 06:56:56 scw-kata-21-default-92494fc088b4493b92bc447331 systemd-networkd[514]: lxccb51fa4117b1: Link DOWN
Apr 13 06:56:56 scw-kata-21-default-92494fc088b4493b92bc447331 systemd-networkd[514]: lxccb51fa4117b1: Lost carrier
Apr 13 06:56:56 scw-kata-21-default-92494fc088b4493b92bc447331 containerd[152640]: time="2021-04-13T06:56:56.074538022Z" level=error msg="RunPodSandbox for &PodSandboxMetadata{Name:untrust2,Uid:027a85a0-dc9f-42f1-941c-9985857a8a59,Namespace:default,Attempt:0,} failed, error" error="failed to create containerd task: Failed to check if grpc server is working: rpc error: code = DeadlineExceeded desc = timed out connecting to vsock 313364734:1024: unknown"
Apr 13 06:56:56 scw-kata-21-default-92494fc088b4493b92bc447331 systemd[151473]: run-netns-cni\x2d30c157fb\x2d49eb\x2d339d\x2dfe32\x2de67fbbaa7ff8.mount: Succeeded.
Apr 13 06:56:56 scw-kata-21-default-92494fc088b4493b92bc447331 systemd[1]: run-netns-cni\x2d30c157fb\x2d49eb\x2d339d\x2dfe32\x2de67fbbaa7ff8.mount: Succeeded.
Apr 13 06:56:56 scw-kata-21-default-92494fc088b4493b92bc447331 kubelet[152651]: E0413 06:56:56.077355  152651 remote_runtime.go:116] "RunPodSandbox from runtime service failed" err="rpc error: code = Unknown desc = failed to create containerd task: Failed to check if grpc server is working: rpc error: code = DeadlineExceeded desc = timed out connecting to vsock 313364734:1024: unknown"