I0417 00:35:27.063012       1 feature_gate.go:245] feature gates: &{map[]}
I0417 00:35:27.063272       1 csi-provisioner.go:139] Version: v3.1.0
I0417 00:35:27.063302       1 csi-provisioner.go:162] Building kube configs for running in cluster...
I0417 00:35:27.063805       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
W0417 00:35:37.064431       1 connection.go:173] Still connecting to unix:///csi/csi-provisioner.sock
W0417 00:35:47.064566       1 connection.go:173] Still connecting to unix:///csi/csi-provisioner.sock
I0417 00:35:49.258473       1 common.go:111] Probing CSI driver for readiness
I0417 00:35:49.258514       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0417 00:35:49.258530       1 connection.go:184] GRPC request: {}
I0417 00:35:49.263802       1 connection.go:186] GRPC response: {}
I0417 00:35:49.263880       1 connection.go:187] GRPC error: <nil>
I0417 00:35:49.263894       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo
I0417 00:35:49.263934       1 connection.go:184] GRPC request: {}
I0417 00:35:49.264541       1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0417 00:35:49.264648       1 connection.go:187] GRPC error: <nil>
I0417 00:35:49.264761       1 csi-provisioner.go:206] Detected CSI driver rbd.csi.ceph.com
I0417 00:35:49.264811       1 csi-provisioner.go:216] Supports migration from in-tree plugin: kubernetes.io/rbd
I0417 00:35:49.264912       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0417 00:35:49.265398       1 common.go:111] Probing CSI driver for readiness
I0417 00:35:49.265420       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0417 00:35:49.265426       1 connection.go:184] GRPC request: {}
I0417 00:35:49.265988       1 connection.go:186] GRPC response: {}
I0417 00:35:49.266017       1 connection.go:187] GRPC error: <nil>
I0417 00:35:49.266028       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0417 00:35:49.266032       1 connection.go:184] GRPC request: {}
I0417 00:35:49.266921       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0417 00:35:49.267268       1 connection.go:187] GRPC error: <nil>
I0417 00:35:49.267283       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0417 00:35:49.267293       1 connection.go:184] GRPC request: {}
I0417 00:35:49.268342       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0417 00:35:49.268466       1 connection.go:187] GRPC error: <nil>
I0417 00:35:49.268616       1 csi-provisioner.go:278] CSI driver does not support PUBLISH_UNPUBLISH_VOLUME, not watching VolumeAttachments
I0417 00:35:49.269288       1 controller.go:732] Using saving PVs to API server in background
I0417 00:35:49.269842       1 leaderelection.go:248] attempting to acquire leader lease kube-system/rbd-csi-ceph-com...
I0417 00:35:49.281580       1 leaderelection.go:258] successfully acquired lease kube-system/rbd-csi-ceph-com
I0417 00:35:49.281747       1 leader_election.go:205] became leader, starting
I0417 00:35:49.281858       1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-6f6497f79c-zd5h9
I0417 00:35:49.282042       1 reflector.go:219] Starting reflector *v1.StorageClass (1h0m0s) from k8s.io/client-go/informers/factory.go:134
I0417 00:35:49.282057       1 reflector.go:255] Listing and watching *v1.StorageClass from k8s.io/client-go/informers/factory.go:134
I0417 00:35:49.282090       1 reflector.go:219] Starting reflector *v1.PersistentVolumeClaim (15m0s) from k8s.io/client-go/informers/factory.go:134
I0417 00:35:49.282105       1 reflector.go:255] Listing and watching *v1.PersistentVolumeClaim from k8s.io/client-go/informers/factory.go:134
I0417 00:35:49.290543       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:35:49.382617       1 shared_informer.go:270] caches populated
I0417 00:35:49.382651       1 shared_informer.go:270] caches populated
I0417 00:35:49.382667       1 controller.go:811] Starting provisioner controller rbd.csi.ceph.com_ceph-csi-rbd-provisioner-6f6497f79c-zd5h9_4e416a31-f4d5-4a5d-9a35-d967297874b8!
I0417 00:35:49.382874       1 clone_controller.go:66] Starting CloningProtection controller
I0417 00:35:49.382914       1 reflector.go:219] Starting reflector *v1.PersistentVolume (15m0s) from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:845
I0417 00:35:49.382925       1 reflector.go:255] Listing and watching *v1.PersistentVolume from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:845
I0417 00:35:49.382958       1 reflector.go:219] Starting reflector *v1.StorageClass (15m0s) from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:848
I0417 00:35:49.382996       1 reflector.go:255] Listing and watching *v1.StorageClass from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:848
I0417 00:35:49.383128       1 clone_controller.go:82] Started CloningProtection controller
I0417 00:35:49.388097       1 volume_store.go:97] Starting save volume queue
I0417 00:35:49.483075       1 shared_informer.go:270] caches populated
I0417 00:35:49.484033       1 controller.go:860] Started provisioner controller rbd.csi.ceph.com_ceph-csi-rbd-provisioner-6f6497f79c-zd5h9_4e416a31-f4d5-4a5d-9a35-d967297874b8!
I0417 00:35:54.299621       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:35:59.307211       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:36:04.316896       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:36:09.324888       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:36:14.334239       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:36:19.341657       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:36:24.348663       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:36:29.357382       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:36:34.366371       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:36:39.373924       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:36:44.381864       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:36:49.392231       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:36:54.398448       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:36:59.404840       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:37:04.413029       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:37:09.420263       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:37:14.432326       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:37:19.439927       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:37:24.447183       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:37:29.454448       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:37:34.466550       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:37:39.479024       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:37:44.486403       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:37:49.497569       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:37:54.503319       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:37:59.512361       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:38:04.517136       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:38:09.527780       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:38:14.535548       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:38:19.544211       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:38:24.554098       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:38:29.562711       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:38:34.575252       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:38:39.586925       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:38:44.598411       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:38:49.608418       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:38:52.349686       1 controller.go:1337] provision "default/test-pvc" class "general": started
I0417 00:38:52.349746       1 controller.go:528] skip translation of storage class for plugin: rbd.csi.ceph.com
I0417 00:38:52.350860       1 event.go:285] Event(v1.ObjectReference{Kind:"PersistentVolumeClaim", Namespace:"default", Name:"test-pvc", UID:"221a973b-c70a-4a37-b48a-1b8cf3084d24", APIVersion:"v1", ResourceVersion:"2199", FieldPath:""}): type: 'Normal' reason: 'Provisioning' External provisioner is provisioning volume for claim "default/test-pvc"
I0417 00:38:52.352543       1 connection.go:183] GRPC call: /csi.v1.Controller/CreateVolume
I0417 00:38:52.352558       1 connection.go:184] GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-221a973b-c70a-4a37-b48a-1b8cf3084d24","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-221a973b-c70a-4a37-b48a-1b8cf3084d24","csi.storage.k8s.io/pvc/name":"test-pvc","csi.storage.k8s.io/pvc/namespace":"default","imageFeatures":"layering","pool":"kube","thickProvision":"false"},"secrets":"***stripped***","volume_capabilities":[{"AccessType":{"Mount":{"fs_type":"ext4","mount_flags":["discard"]}},"access_mode":{"mode":1}}]}
I0417 00:38:53.888169       1 connection.go:186] GRPC response: {"volume":{"capacity_bytes":5368709120,"volume_context":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-221a973b-c70a-4a37-b48a-1b8cf3084d24","csi.storage.k8s.io/pvc/name":"test-pvc","csi.storage.k8s.io/pvc/namespace":"default","imageFeatures":"layering","imageName":"csi-vol-cec0c722-39f5-11f1-b0fb-a62978eb0e1b","journalPool":"kube","pool":"kube","thickProvision":"false"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-cec0c722-39f5-11f1-b0fb-a62978eb0e1b"}}
I0417 00:38:53.888345       1 connection.go:187] GRPC error: <nil>
I0417 00:38:53.888355       1 controller.go:774] create volume rep: {CapacityBytes:5368709120 VolumeId:0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-cec0c722-39f5-11f1-b0fb-a62978eb0e1b VolumeContext:map[clusterID:4837cbf8-4f90-4300-b3f6-726c9b9f89b4 csi.storage.k8s.io/pv/name:pvc-221a973b-c70a-4a37-b48a-1b8cf3084d24 csi.storage.k8s.io/pvc/name:test-pvc csi.storage.k8s.io/pvc/namespace:default imageFeatures:layering imageName:csi-vol-cec0c722-39f5-11f1-b0fb-a62978eb0e1b journalPool:kube pool:kube thickProvision:false] ContentSource:<nil> AccessibleTopology:[] XXX_NoUnkeyedLiteral:{} XXX_unrecognized:[] XXX_sizecache:0}
I0417 00:38:53.888393       1 controller.go:858] successfully created PV pvc-221a973b-c70a-4a37-b48a-1b8cf3084d24 for PVC test-pvc and csi volume name 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-cec0c722-39f5-11f1-b0fb-a62978eb0e1b
I0417 00:38:53.888406       1 controller.go:874] successfully created PV {GCEPersistentDisk:nil AWSElasticBlockStore:nil HostPath:nil Glusterfs:nil NFS:nil RBD:nil ISCSI:nil Cinder:nil CephFS:nil FC:nil Flocker:nil FlexVolume:nil AzureFile:nil VsphereVolume:nil Quobyte:nil AzureDisk:nil PhotonPersistentDisk:nil PortworxVolume:nil ScaleIO:nil Local:nil StorageOS:nil CSI:&CSIPersistentVolumeSource{Driver:rbd.csi.ceph.com,VolumeHandle:0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-cec0c722-39f5-11f1-b0fb-a62978eb0e1b,ReadOnly:false,FSType:ext4,VolumeAttributes:map[string]string{clusterID: 4837cbf8-4f90-4300-b3f6-726c9b9f89b4,csi.storage.k8s.io/pv/name: pvc-221a973b-c70a-4a37-b48a-1b8cf3084d24,csi.storage.k8s.io/pvc/name: test-pvc,csi.storage.k8s.io/pvc/namespace: default,imageFeatures: layering,imageName: csi-vol-cec0c722-39f5-11f1-b0fb-a62978eb0e1b,journalPool: kube,pool: kube,storage.kubernetes.io/csiProvisionerIdentity: 1776386149268-8081-rbd.csi.ceph.com,thickProvision: false,},ControllerPublishSecretRef:nil,NodeStageSecretRef:&SecretReference{Name:csi-rbd-secret,Namespace:kube-system,},NodePublishSecretRef:nil,ControllerExpandSecretRef:&SecretReference{Name:csi-rbd-secret,Namespace:kube-system,},}}
I0417 00:38:53.888802       1 controller.go:1442] provision "default/test-pvc" class "general": volume "pvc-221a973b-c70a-4a37-b48a-1b8cf3084d24" provisioned
I0417 00:38:53.888815       1 controller.go:1455] provision "default/test-pvc" class "general": succeeded
I0417 00:38:53.888822       1 volume_store.go:154] Saving volume pvc-221a973b-c70a-4a37-b48a-1b8cf3084d24
I0417 00:38:53.898407       1 volume_store.go:157] Volume pvc-221a973b-c70a-4a37-b48a-1b8cf3084d24 saved
I0417 00:38:53.898453       1 controller.go:1069] Claim processing succeeded, removing PVC 221a973b-c70a-4a37-b48a-1b8cf3084d24 from claims in progress
I0417 00:38:53.900864       1 event.go:285] Event(v1.ObjectReference{Kind:"PersistentVolumeClaim", Namespace:"default", Name:"test-pvc", UID:"221a973b-c70a-4a37-b48a-1b8cf3084d24", APIVersion:"v1", ResourceVersion:"2199", FieldPath:""}): type: 'Normal' reason: 'ProvisioningSucceeded' Successfully provisioned volume pvc-221a973b-c70a-4a37-b48a-1b8cf3084d24
I0417 00:38:54.617139       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:38:59.625877       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:39:04.633004       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:39:09.643610       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:39:14.654120       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:39:19.661887       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:39:24.674683       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:39:29.682121       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:39:34.692560       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0417 00:39:39.704667       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com