I0218 10:24:43.897600   43101 cephcsi.go:191] Driver version: v3.11.0 and Git version: bc24b5eca87626d690a29effa9d7420cc0154a7a
I0218 10:24:43.897989   43101 cephcsi.go:268] Initial PID limit is set to -1
I0218 10:24:43.898120   43101 cephcsi.go:274] Reconfigured PID limit to -1 (max)
I0218 10:24:43.898166   43101 cephcsi.go:223] Starting driver type: rbd with name: rbd.csi.ceph.com
I0218 10:24:43.934367   43101 mount_linux.go:282] Detected umount with safe 'not mounted' behavior
I0218 10:24:44.046825   43101 rbd_attach.go:242] nbd module loaded
I0218 10:24:44.046902   43101 rbd_attach.go:256] kernel version "5.15.0-170-generic" supports cookie feature
I0218 10:24:44.071599   43101 rbd_attach.go:272] rbd-nbd tool supports cookie feature
I0218 10:24:44.078992   43101 server.go:114] listening for CSI-Addons requests on address: &net.UnixAddr{Name:"/csi/csi-addons.sock", Net:"unix"}
I0218 10:24:44.079394   43101 server.go:117] Listening for connections on address: &net.UnixAddr{Name:"//csi/csi.sock", Net:"unix"}
I0218 10:24:46.986601   43101 utils.go:198] ID: 1 GRPC call: /csi.v1.Identity/GetPluginInfo
I0218 10:24:46.987964   43101 utils.go:199] ID: 1 GRPC request: {}
I0218 10:24:46.988061   43101 identityserver-default.go:40] ID: 1 Using default GetPluginInfo
I0218 10:24:46.988215   43101 utils.go:205] ID: 1 GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.11.0"}
I0218 10:24:47.378707   43101 utils.go:198] ID: 2 GRPC call: /csi.v1.Node/NodeGetInfo
I0218 10:24:47.378940   43101 utils.go:199] ID: 2 GRPC request: {}
I0218 10:24:47.378958   43101 nodeserver-default.go:45] ID: 2 Using default NodeGetInfo
I0218 10:24:47.379112   43101 utils.go:205] ID: 2 GRPC response: {"accessible_topology":{},"node_id":"instance"}
I0218 10:25:47.430139   43101 utils.go:198] ID: 3 GRPC call: /csi.v1.Identity/Probe
I0218 10:25:47.430377   43101 utils.go:199] ID: 3 GRPC request: {}
I0218 10:25:47.430501   43101 utils.go:205] ID: 3 GRPC response: {}
I0218 10:26:47.371028   43101 utils.go:198] ID: 4 GRPC call: /csi.v1.Identity/Probe
I0218 10:26:47.371072   43101 utils.go:199] ID: 4 GRPC request: {}
I0218 10:26:47.371093   43101 utils.go:205] ID: 4 GRPC response: {}
I0218 10:27:47.370856   43101 utils.go:198] ID: 5 GRPC call: /csi.v1.Identity/Probe
I0218 10:27:47.370908   43101 utils.go:199] ID: 5 GRPC request: {}
I0218 10:27:47.370932   43101 utils.go:205] ID: 5 GRPC response: {}
I0218 10:28:47.377262   43101 utils.go:198] ID: 6 GRPC call: /csi.v1.Identity/Probe
I0218 10:28:47.377310   43101 utils.go:199] ID: 6 GRPC request: {}
I0218 10:28:47.377332   43101 utils.go:205] ID: 6 GRPC response: {}
I0218 10:29:47.428354   43101 utils.go:198] ID: 7 GRPC call: /csi.v1.Identity/Probe
I0218 10:29:47.428527   43101 utils.go:199] ID: 7 GRPC request: {}
I0218 10:29:47.428613   43101 utils.go:205] ID: 7 GRPC response: {}
I0218 10:30:47.391273   43101 utils.go:198] ID: 8 GRPC call: /csi.v1.Identity/Probe
I0218 10:30:47.391305   43101 utils.go:199] ID: 8 GRPC request: {}
I0218 10:30:47.391318   43101 utils.go:205] ID: 8 GRPC response: {}
I0218 10:30:51.268275   43101 utils.go:198] ID: 9 GRPC call: /csi.v1.Node/NodeGetCapabilities
I0218 10:30:51.268493   43101 utils.go:199] ID: 9 GRPC request: {}
I0218 10:30:51.268669   43101 utils.go:205] ID: 9 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":3}}},{"Type":{"Rpc":{"type":5}}}]}
I0218 10:30:51.273793   43101 utils.go:198] ID: 10 GRPC call: /csi.v1.Node/NodeGetCapabilities
I0218 10:30:51.273822   43101 utils.go:199] ID: 10 GRPC request: {}
I0218 10:30:51.273925   43101 utils.go:205] ID: 10 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":3}}},{"Type":{"Rpc":{"type":5}}}]}
I0218 10:30:51.274888   43101 utils.go:198] ID: 11 GRPC call: /csi.v1.Node/NodeGetCapabilities
I0218 10:30:51.274909   43101 utils.go:199] ID: 11 GRPC request: {}
I0218 10:30:51.274969   43101 utils.go:205] ID: 11 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":3}}},{"Type":{"Rpc":{"type":5}}}]}
I0218 10:30:51.276618   43101 utils.go:198] ID: 12 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae GRPC call: /csi.v1.Node/NodeStageVolume
I0218 10:30:51.276813   43101 utils.go:199] ID: 12 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae GRPC request: {"secrets":"***stripped***","staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/rbd.csi.ceph.com/d9e60b48fa3f35bfb29447a75fdc1b4af42419b35ae02bc464380fb8c864647e/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-c594c2a6-a4fd-44b6-bee8-c29729b372ae","journalPool":"kube","pool":"kube","storage.kubernetes.io/csiProvisionerIdentity":"1771410303751-2610-rbd.csi.ceph.com"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae"}
I0218 10:30:51.297661   43101 omap.go:89] ID: 12 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae got omap values: (pool="kube", namespace="", name="csi.volume.c594c2a6-a4fd-44b6-bee8-c29729b372ae"): map[csi.imageid:37d5bf88c73f csi.imagename:csi-vol-c594c2a6-a4fd-44b6-bee8-c29729b372ae csi.volname:pvc-a53b6fba-bc06-4509-b746-0a50644a6ad0 csi.volume.owner:default]
I0218 10:30:51.355269   43101 rbd_util.go:352] ID: 12 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae checking for ImageFeatures: [layering]
I0218 10:30:51.449985   43101 cephcmds.go:105] ID: 12 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae command succeeded: rbd [device list --format=json --device-type krbd]
I0218 10:30:51.470178   43101 rbd_attach.go:437] ID: 12 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae rbd: map mon 199.19.213.152
I0218 10:30:51.587118   43101 cephcmds.go:105] ID: 12 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae command succeeded: rbd [--id kube -m 199.19.213.152 --keyfile=***stripped*** map kube/csi-vol-c594c2a6-a4fd-44b6-bee8-c29729b372ae --device-type krbd --options noudev]
I0218 10:30:51.587151   43101 nodeserver.go:422] ID: 12 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae rbd image: kube/csi-vol-c594c2a6-a4fd-44b6-bee8-c29729b372ae was successfully mapped at /dev/rbd0
I0218 10:30:51.587305   43101 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])
I0218 10:30:51.604889   43101 mount_linux.go:580] Output: ""
I0218 10:30:53.475503   43101 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])
I0218 10:30:53.536334   43101 mount_linux.go:580] Output: "DEVNAME=/dev/rbd0\nTYPE=ext4\n"
I0218 10:30:53.536359   43101 mount_linux.go:439] Checking for issues with fsck on disk: /dev/rbd0
I0218 10:30:54.232892   43101 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/d9e60b48fa3f35bfb29447a75fdc1b4af42419b35ae02bc464380fb8c864647e/globalmount/0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae
I0218 10:30:54.232922   43101 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/d9e60b48fa3f35bfb29447a75fdc1b4af42419b35ae02bc464380fb8c864647e/globalmount/0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae)
I0218 10:30:54.427007   43101 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])
I0218 10:30:54.434597   43101 mount_linux.go:580] Output: "DEVNAME=/dev/rbd0\nTYPE=ext4\n"
I0218 10:30:54.434615   43101 resizefs_linux.go:137] ResizeFs.needResize - checking mounted volume /dev/rbd0
I0218 10:30:54.445117   43101 resizefs_linux.go:141] Ext size: filesystem size=5368709120, block size=4096
I0218 10:30:54.445139   43101 resizefs_linux.go:156] Volume /dev/rbd0: device size=5368709120, filesystem size=5368709120, block size=4096
I0218 10:30:54.445171   43101 nodeserver.go:382] ID: 12 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae rbd: successfully mounted volume 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae to stagingTargetPath /var/lib/kubelet/plugins/kubernetes.io/csi/rbd.csi.ceph.com/d9e60b48fa3f35bfb29447a75fdc1b4af42419b35ae02bc464380fb8c864647e/globalmount/0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae
I0218 10:30:54.445313   43101 utils.go:205] ID: 12 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae GRPC response: {}
I0218 10:30:54.447002   43101 utils.go:198] ID: 13 GRPC call: /csi.v1.Node/NodeGetCapabilities
I0218 10:30:54.447023   43101 utils.go:199] ID: 13 GRPC request: {}
I0218 10:30:54.447125   43101 utils.go:205] ID: 13 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":3}}},{"Type":{"Rpc":{"type":5}}}]}
I0218 10:30:54.453565   43101 utils.go:198] ID: 14 GRPC call: /csi.v1.Node/NodeGetCapabilities
I0218 10:30:54.453585   43101 utils.go:199] ID: 14 GRPC request: {}
I0218 10:30:54.453781   43101 utils.go:205] ID: 14 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":3}}},{"Type":{"Rpc":{"type":5}}}]}
I0218 10:30:54.454595   43101 utils.go:198] ID: 15 GRPC call: /csi.v1.Node/NodeGetCapabilities
I0218 10:30:54.454611   43101 utils.go:199] ID: 15 GRPC request: {}
I0218 10:30:54.454724   43101 utils.go:205] ID: 15 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":3}}},{"Type":{"Rpc":{"type":5}}}]}
I0218 10:30:54.455384   43101 utils.go:198] ID: 16 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae GRPC call: /csi.v1.Node/NodePublishVolume
I0218 10:30:54.455715   43101 utils.go:199] ID: 16 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae GRPC request: {"staging_target_path":"/var/lib/kubelet/plugins/kubernetes.io/csi/rbd.csi.ceph.com/d9e60b48fa3f35bfb29447a75fdc1b4af42419b35ae02bc464380fb8c864647e/globalmount","target_path":"/var/lib/kubelet/pods/1779a177-c21a-4e64-af3f-b97384d8fd6a/volumes/kubernetes.io~csi/pvc-a53b6fba-bc06-4509-b746-0a50644a6ad0/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-c594c2a6-a4fd-44b6-bee8-c29729b372ae","journalPool":"kube","pool":"kube","storage.kubernetes.io/csiProvisionerIdentity":"1771410303751-2610-rbd.csi.ceph.com"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae"}
I0218 10:30:54.455828   43101 nodeserver.go:859] ID: 16 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae target /var/lib/kubelet/pods/1779a177-c21a-4e64-af3f-b97384d8fd6a/volumes/kubernetes.io~csi/pvc-a53b6fba-bc06-4509-b746-0a50644a6ad0/mount
isBlock false
fstype ext4
stagingPath /var/lib/kubelet/plugins/kubernetes.io/csi/rbd.csi.ceph.com/d9e60b48fa3f35bfb29447a75fdc1b4af42419b35ae02bc464380fb8c864647e/globalmount/0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae
readonly false
mountflags [bind _netdev discard]
I0218 10:30:54.455868   43101 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/d9e60b48fa3f35bfb29447a75fdc1b4af42419b35ae02bc464380fb8c864647e/globalmount/0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae /var/lib/kubelet/pods/1779a177-c21a-4e64-af3f-b97384d8fd6a/volumes/kubernetes.io~csi/pvc-a53b6fba-bc06-4509-b746-0a50644a6ad0/mount)
I0218 10:30:54.465624   43101 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/d9e60b48fa3f35bfb29447a75fdc1b4af42419b35ae02bc464380fb8c864647e/globalmount/0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae /var/lib/kubelet/pods/1779a177-c21a-4e64-af3f-b97384d8fd6a/volumes/kubernetes.io~csi/pvc-a53b6fba-bc06-4509-b746-0a50644a6ad0/mount)
I0218 10:30:54.469201   43101 nodeserver.go:742] ID: 16 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae rbd: successfully mounted stagingPath /var/lib/kubelet/plugins/kubernetes.io/csi/rbd.csi.ceph.com/d9e60b48fa3f35bfb29447a75fdc1b4af42419b35ae02bc464380fb8c864647e/globalmount/0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae to targetPath /var/lib/kubelet/pods/1779a177-c21a-4e64-af3f-b97384d8fd6a/volumes/kubernetes.io~csi/pvc-a53b6fba-bc06-4509-b746-0a50644a6ad0/mount
I0218 10:30:54.469240   43101 utils.go:205] ID: 16 Req-ID: 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae GRPC response: {}
I0218 10:31:05.069275   43101 utils.go:198] ID: 17 GRPC call: /csi.v1.Node/NodeGetCapabilities
I0218 10:31:05.069322   43101 utils.go:199] ID: 17 GRPC request: {}
I0218 10:31:05.069490   43101 utils.go:205] ID: 17 GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":2}}},{"Type":{"Rpc":{"type":3}}},{"Type":{"Rpc":{"type":5}}}]}
I0218 10:31:05.070532   43101 utils.go:198] ID: 18 GRPC call: /csi.v1.Node/NodeGetVolumeStats
I0218 10:31:05.070608   43101 utils.go:199] ID: 18 GRPC request: {"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-c594c2a6-a4fd-44b6-bee8-c29729b372ae","volume_path":"/var/lib/kubelet/pods/1779a177-c21a-4e64-af3f-b97384d8fd6a/volumes/kubernetes.io~csi/pvc-a53b6fba-bc06-4509-b746-0a50644a6ad0/mount"}
I0218 10:31:05.070759   43101 utils.go:205] ID: 18 GRPC response: {"usage":[{"available":5179580416,"total":5196382208,"unit":1,"used":24576},{"available":327669,"total":327680,"unit":2,"used":11}],"volume_condition":{"message":"volume is in a healthy condition"}}