I0526 08:17:28.190722   52482 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0526 08:17:28.190937   52482 cephcsi.go:268] Initial PID limit is set to -1
I0526 08:17:28.190988   52482 cephcsi.go:274] Reconfigured PID limit to -1 (max)
I0526 08:17:28.191010   52482 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0526 08:17:28.206393   52482 mount_linux.go:282] Detected umount with safe 'not mounted' behavior
I0526 08:17:28.228966   52482 rbd_attach.go:242] nbd module loaded
I0526 08:17:28.229034   52482 rbd_attach.go:256] kernel version "5.15.0-179-generic" supports cookie feature
I0526 08:17:28.254401   52482 rbd_attach.go:272] rbd-nbd tool supports cookie feature
I0526 08:17:28.254923   52482 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0526 08:17:28.254982   52482 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi.sock", Net:"unix"}
I0526 08:17:29.115011   52482 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/GetPluginInfo
I0526 08:17:29.116653   52482 utils.go:199] ID: 1 GRPC request: {}
I0526 08:17:29.116672   52482 identityserver-default.go:40] ID: 1 Using default GetPluginInfo
I0526 08:17:29.116716   52482 utils.go:205] ID: 1 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0526 08:17:29.304128   52482 utils.go:198] ID: 2 GRPC call: /csi.v1.Node/NodeGetInfo
I0526 08:17:29.304336   52482 utils.go:199] ID: 2 GRPC request: {}
I0526 08:17:29.304356   52482 nodeserver-default.go:45] ID: 2 Using default NodeGetInfo
I0526 08:17:29.304488   52482 utils.go:205] ID: 2 GRPC response: {"accessible_topology":{},"node_id":"instance"}
I0526 08:17:41.801138   52482 utils.go:198] ID: 3 GRPC call: /csi.v1.Node/NodeGetCapabilities
I0526 08:17:41.801273   52482 utils.go:199] ID: 3 GRPC request: {}
I0526 08:17:41.801609   52482 utils.go:205] ID: 3 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":3}}},{"Type":{"Rpc":{"type":5}}}]}
I0526 08:17:41.878333   52482 utils.go:198] ID: 4 GRPC call: /csi.v1.Node/NodeGetCapabilities
I0526 08:17:41.878377   52482 utils.go:199] ID: 4 GRPC request: {}
I0526 08:17:41.878469   52482 utils.go:205] ID: 4 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":3}}},{"Type":{"Rpc":{"type":5}}}]}
I0526 08:17:41.879822   52482 utils.go:198] ID: 5 GRPC call: /csi.v1.Node/NodeGetCapabilities
I0526 08:17:41.879847   52482 utils.go:199] ID: 5 GRPC request: {}
I0526 08:17:41.879962   52482 utils.go:205] ID: 5 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":3}}},{"Type":{"Rpc":{"type":5}}}]}
I0526 08:17:41.882527   52482 utils.go:198] ID: 6 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb GRPC call: /csi.v1.Node/NodeStageVolume
I0526 08:17:41.883077   52482 utils.go:199] ID: 6 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb GRPC request: {"secrets":"***stripped***","staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/rbd.csi.ceph.com/e6ab4e9aa2bff3cb05b475b23d2b9359bb9cffd888f40fb35eea463e3489f1f6/globalmount","volume_capability":{"AccessType":{"Mount":{"fs_type":"ext4","mount_flags":["discard"]}},"access_mode":{"mode":7}},"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-41a89609-f1d2-4e88-8771-842ac86245bb","journalPool":"kube","pool":"kube","storage.kubernetes.io/csiProvisionerIdentity":"1779783450642-5439-rbd.csi.ceph.com"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb"}
I0526 08:17:41.903708   52482 omap.go:89] ID: 6 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb got omap values: (pool="kube", namespace="", name="csi.volume.41a89609-f1d2-4e88-8771-842ac86245bb"): map[csi.imageid:37c1a28e1539 csi.imagename:csi-vol-41a89609-f1d2-4e88-8771-842ac86245bb csi.volname:pvc-4456d60b-a884-4ff7-953b-ec28c7229036 csi.volume.owner:default]
I0526 08:17:42.296757   52482 rbd_util.go:352] ID: 6 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb checking for ImageFeatures: [layering]
I0526 08:17:42.327991   52482 cephcmds.go:105] ID: 6 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb command succeeded: rbd [device list --format=json --device-type krbd]
I0526 08:17:42.445892   52482 rbd_attach.go:437] ID: 6 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb rbd: map mon 199.19.213.102
I0526 08:17:42.619731   52482 cephcmds.go:105] ID: 6 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb command succeeded: rbd [--id kube -m 199.19.213.102 --keyfile=***stripped*** map kube/csi-vol-41a89609-f1d2-4e88-8771-842ac86245bb --device-type krbd --options noudev]
I0526 08:17:42.619827   52482 nodeserver.go:422] ID: 6 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb rbd image: kube/csi-vol-41a89609-f1d2-4e88-8771-842ac86245bb was successfully mapped at /dev/rbd0
I0526 08:17:42.620083   52482 mount_linux.go:577] Attempting to determine if disk "/dev/rbd0" is formatted using blkid with args: ([-p -s TYPE -s PTTYPE -o export /dev/rbd0])
I0526 08:17:42.630734   52482 mount_linux.go:580] Output: ""
I0526 08:17:43.670177   52482 mount_linux.go:577] Attempting to determine if disk "/dev/rbd0" is formatted using blkid with args: ([-p -s TYPE -s PTTYPE -o export /dev/rbd0])
I0526 08:17:43.682264   52482 mount_linux.go:580] Output: "DEVNAME=/dev/rbd0\nTYPE=ext4\n"
I0526 08:17:43.682282   52482 mount_linux.go:439] Checking for issues with fsck on disk: /dev/rbd0
I0526 08:17:44.396881   52482 mount_linux.go:544] Attempting to mount disk /dev/rbd0 in ext4 format at /var/lib/kubelet/plugins/kubernetes.io/csi/rbd.csi.ceph.com/e6ab4e9aa2bff3cb05b475b23d2b9359bb9cffd888f40fb35eea463e3489f1f6/globalmount/0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb
I0526 08:17:44.396936   52482 mount_linux.go:218] Mounting cmd (mount) with arguments (-t ext4 -o _netdev,discard,defaults /dev/rbd0 /var/lib/kubelet/plugins/kubernetes.io/csi/rbd.csi.ceph.com/e6ab4e9aa2bff3cb05b475b23d2b9359bb9cffd888f40fb35eea463e3489f1f6/globalmount/0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb)
I0526 08:17:44.799052   52482 mount_linux.go:577] Attempting to determine if disk "/dev/rbd0" is formatted using blkid with args: ([-p -s TYPE -s PTTYPE -o export /dev/rbd0])
I0526 08:17:44.809451   52482 mount_linux.go:580] Output: "DEVNAME=/dev/rbd0\nTYPE=ext4\n"
I0526 08:17:44.809475   52482 resizefs_linux.go:137] ResizeFs.needResize - checking mounted volume /dev/rbd0
I0526 08:17:44.812652   52482 resizefs_linux.go:141] Ext size: filesystem size=5368709120, block size=4096
I0526 08:17:44.812692   52482 resizefs_linux.go:156] Volume /dev/rbd0: device size=5368709120, filesystem size=5368709120, block size=4096
I0526 08:17:44.812729   52482 nodeserver.go:382] ID: 6 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb rbd: successfully mounted volume 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb to stagingTargetPath /var/lib/kubelet/plugins/kubernetes.io/csi/rbd.csi.ceph.com/e6ab4e9aa2bff3cb05b475b23d2b9359bb9cffd888f40fb35eea463e3489f1f6/globalmount/0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb
I0526 08:17:44.813051   52482 utils.go:205] ID: 6 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb GRPC response: {}
I0526 08:17:44.815427   52482 utils.go:198] ID: 7 GRPC call: /csi.v1.Node/NodeGetCapabilities
I0526 08:17:44.815468   52482 utils.go:199] ID: 7 GRPC request: {}
I0526 08:17:44.815787   52482 utils.go:205] ID: 7 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":3}}},{"Type":{"Rpc":{"type":5}}}]}
I0526 08:17:44.821480   52482 utils.go:198] ID: 8 GRPC call: /csi.v1.Node/NodeGetCapabilities
I0526 08:17:44.821516   52482 utils.go:199] ID: 8 GRPC request: {}
I0526 08:17:44.821602   52482 utils.go:205] ID: 8 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":3}}},{"Type":{"Rpc":{"type":5}}}]}
I0526 08:17:44.822889   52482 utils.go:198] ID: 9 GRPC call: /csi.v1.Node/NodeGetCapabilities
I0526 08:17:44.822932   52482 utils.go:199] ID: 9 GRPC request: {}
I0526 08:17:44.823092   52482 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":3}}},{"Type":{"Rpc":{"type":5}}}]}
I0526 08:17:44.824114   52482 utils.go:198] ID: 10 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb GRPC call: /csi.v1.Node/NodePublishVolume
I0526 08:17:44.824325   52482 utils.go:199] ID: 10 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/rbd.csi.ceph.com/e6ab4e9aa2bff3cb05b475b23d2b9359bb9cffd888f40fb35eea463e3489f1f6/globalmount","target_path":"/var/lib/kubelet/pods/b96e4d78-a7a4-4bc0-b820-85cd73aba8ce/volumes/kubernetes.io~csi/pvc-4456d60b-a884-4ff7-953b-ec28c7229036/mount","volume_capability":{"AccessType":{"Mount":{"fs_type":"ext4","mount_flags":["discard"]}},"access_mode":{"mode":7}},"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","imageFeatures":"layering","imageName":"csi-vol-41a89609-f1d2-4e88-8771-842ac86245bb","journalPool":"kube","pool":"kube","storage.kubernetes.io/csiProvisionerIdentity":"1779783450642-5439-rbd.csi.ceph.com"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb"}
I0526 08:17:44.824495   52482 nodeserver.go:859] ID: 10 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb target /var/lib/kubelet/pods/b96e4d78-a7a4-4bc0-b820-85cd73aba8ce/volumes/kubernetes.io~csi/pvc-4456d60b-a884-4ff7-953b-ec28c7229036/mount
isBlock false
fstype ext4
stagingPath /var/lib/kubelet/plugins/kubernetes.io/csi/rbd.csi.ceph.com/e6ab4e9aa2bff3cb05b475b23d2b9359bb9cffd888f40fb35eea463e3489f1f6/globalmount/0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb
readonly false
mountflags [bind _netdev discard]
I0526 08:17:44.824518   52482 mount_linux.go:218] Mounting cmd (mount) with arguments (-t ext4 -o bind,_netdev /var/lib/kubelet/plugins/kubernetes.io/csi/rbd.csi.ceph.com/e6ab4e9aa2bff3cb05b475b23d2b9359bb9cffd888f40fb35eea463e3489f1f6/globalmount/0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb /var/lib/kubelet/pods/b96e4d78-a7a4-4bc0-b820-85cd73aba8ce/volumes/kubernetes.io~csi/pvc-4456d60b-a884-4ff7-953b-ec28c7229036/mount)
I0526 08:17:44.829432   52482 mount_linux.go:218] Mounting cmd (mount) with arguments (-t ext4 -o bind,remount,_netdev,discard /var/lib/kubelet/plugins/kubernetes.io/csi/rbd.csi.ceph.com/e6ab4e9aa2bff3cb05b475b23d2b9359bb9cffd888f40fb35eea463e3489f1f6/globalmount/0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb /var/lib/kubelet/pods/b96e4d78-a7a4-4bc0-b820-85cd73aba8ce/volumes/kubernetes.io~csi/pvc-4456d60b-a884-4ff7-953b-ec28c7229036/mount)
I0526 08:17:44.832186   52482 nodeserver.go:742] ID: 10 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb rbd: successfully mounted stagingPath /var/lib/kubelet/plugins/kubernetes.io/csi/rbd.csi.ceph.com/e6ab4e9aa2bff3cb05b475b23d2b9359bb9cffd888f40fb35eea463e3489f1f6/globalmount/0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb to targetPath /var/lib/kubelet/pods/b96e4d78-a7a4-4bc0-b820-85cd73aba8ce/volumes/kubernetes.io~csi/pvc-4456d60b-a884-4ff7-953b-ec28c7229036/mount
I0526 08:17:44.832309   52482 utils.go:205] ID: 10 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb GRPC response: {}
I0526 08:17:56.920359   52482 utils.go:198] ID: 11 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb GRPC call: /csi.v1.Node/NodeUnpublishVolume
I0526 08:17:56.920580   52482 utils.go:199] ID: 11 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb GRPC request: {"target_path":"/var/lib/kubelet/pods/b96e4d78-a7a4-4bc0-b820-85cd73aba8ce/volumes/kubernetes.io~csi/pvc-4456d60b-a884-4ff7-953b-ec28c7229036/mount","volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb"}
I0526 08:17:56.920688   52482 mount_linux.go:360] Unmounting /var/lib/kubelet/pods/b96e4d78-a7a4-4bc0-b820-85cd73aba8ce/volumes/kubernetes.io~csi/pvc-4456d60b-a884-4ff7-953b-ec28c7229036/mount
I0526 08:17:56.924724   52482 nodeserver.go:945] ID: 11 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb rbd: successfully unbound volume 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb from /var/lib/kubelet/pods/b96e4d78-a7a4-4bc0-b820-85cd73aba8ce/volumes/kubernetes.io~csi/pvc-4456d60b-a884-4ff7-953b-ec28c7229036/mount
I0526 08:17:56.924792   52482 utils.go:205] ID: 11 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb GRPC response: {}
I0526 08:17:57.022969   52482 utils.go:198] ID: 12 GRPC call: /csi.v1.Node/NodeGetCapabilities
I0526 08:17:57.023026   52482 utils.go:199] ID: 12 GRPC request: {}
I0526 08:17:57.023195   52482 utils.go:205] ID: 12 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":3}}},{"Type":{"Rpc":{"type":5}}}]}
I0526 08:17:57.024358   52482 utils.go:198] ID: 13 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb GRPC call: /csi.v1.Node/NodeUnstageVolume
I0526 08:17:57.024463   52482 utils.go:199] ID: 13 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/rbd.csi.ceph.com/e6ab4e9aa2bff3cb05b475b23d2b9359bb9cffd888f40fb35eea463e3489f1f6/globalmount","volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb"}
I0526 08:17:57.024558   52482 mount_linux.go:360] Unmounting /var/lib/kubelet/plugins/kubernetes.io/csi/rbd.csi.ceph.com/e6ab4e9aa2bff3cb05b475b23d2b9359bb9cffd888f40fb35eea463e3489f1f6/globalmount/0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb
I0526 08:17:57.079475   52482 nodeserver.go:1001] ID: 13 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb successfully unmounted volume (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb) from staging path (/var/lib/kubelet/plugins/kubernetes.io/csi/rbd.csi.ceph.com/e6ab4e9aa2bff3cb05b475b23d2b9359bb9cffd888f40fb35eea463e3489f1f6/globalmount/0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb)
I0526 08:17:57.145478   52482 cephcmds.go:105] ID: 13 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb command succeeded: rbd [unmap kube/csi-vol-41a89609-f1d2-4e88-8771-842ac86245bb --device-type krbd --options noudev]
I0526 08:17:57.145549   52482 nodeserver.go:1059] ID: 13 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb successfully unmapped volume (0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb)
I0526 08:17:57.145837   52482 utils.go:205] ID: 13 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-41a89609-f1d2-4e88-8771-842ac86245bb GRPC response: {}