I0505 12:55:01.165271       1 feature_gate.go:245] feature gates: &{map[]}
I0505 12:55:01.165325       1 csi-provisioner.go:139] Version: v3.1.0
I0505 12:55:01.165368       1 csi-provisioner.go:162] Building kube configs for running in cluster...
I0505 12:55:01.166081       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
W0505 12:55:11.166492       1 connection.go:173] Still connecting to unix:///csi/csi-provisioner.sock
W0505 12:55:21.166229       1 connection.go:173] Still connecting to unix:///csi/csi-provisioner.sock
I0505 12:55:22.652457       1 common.go:111] Probing CSI driver for readiness
I0505 12:55:22.652748       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0505 12:55:22.652905       1 connection.go:184] GRPC request: {}
I0505 12:55:22.658783       1 connection.go:186] GRPC response: {}
I0505 12:55:22.658957       1 connection.go:187] GRPC error: <nil>
I0505 12:55:22.659011       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo
I0505 12:55:22.659083       1 connection.go:184] GRPC request: {}
I0505 12:55:22.660096       1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0505 12:55:22.660279       1 connection.go:187] GRPC error: <nil>
I0505 12:55:22.660301       1 csi-provisioner.go:206] Detected CSI driver rbd.csi.ceph.com
I0505 12:55:22.660314       1 csi-provisioner.go:216] Supports migration from in-tree plugin: kubernetes.io/rbd
I0505 12:55:22.663273       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0505 12:55:22.664189       1 common.go:111] Probing CSI driver for readiness
I0505 12:55:22.664244       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0505 12:55:22.664258       1 connection.go:184] GRPC request: {}
I0505 12:55:22.665037       1 connection.go:186] GRPC response: {}
I0505 12:55:22.665063       1 connection.go:187] GRPC error: <nil>
I0505 12:55:22.665090       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0505 12:55:22.665120       1 connection.go:184] GRPC request: {}
I0505 12:55:22.666830       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0505 12:55:22.667272       1 connection.go:187] GRPC error: <nil>
I0505 12:55:22.667434       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0505 12:55:22.667487       1 connection.go:184] GRPC request: {}
I0505 12:55:22.668811       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0505 12:55:22.668952       1 connection.go:187] GRPC error: <nil>
I0505 12:55:22.669014       1 csi-provisioner.go:278] CSI driver does not support PUBLISH_UNPUBLISH_VOLUME, not watching VolumeAttachments
I0505 12:55:22.669537       1 controller.go:732] Using saving PVs to API server in background
I0505 12:55:22.669983       1 leaderelection.go:248] attempting to acquire leader lease kube-system/rbd-csi-ceph-com...
I0505 12:55:22.682531       1 leaderelection.go:258] successfully acquired lease kube-system/rbd-csi-ceph-com
I0505 12:55:22.682734       1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-6f6497f79c-xnjqt
I0505 12:55:22.682844       1 leader_election.go:205] became leader, starting
I0505 12:55:22.683232       1 reflector.go:219] Starting reflector *v1.StorageClass (1h0m0s) from k8s.io/client-go/informers/factory.go:134
I0505 12:55:22.683251       1 reflector.go:255] Listing and watching *v1.StorageClass from k8s.io/client-go/informers/factory.go:134
I0505 12:55:22.683380       1 reflector.go:219] Starting reflector *v1.PersistentVolumeClaim (15m0s) from k8s.io/client-go/informers/factory.go:134
I0505 12:55:22.683406       1 reflector.go:255] Listing and watching *v1.PersistentVolumeClaim from k8s.io/client-go/informers/factory.go:134
I0505 12:55:22.690101       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:55:22.783250       1 shared_informer.go:270] caches populated
I0505 12:55:22.783313       1 shared_informer.go:270] caches populated
I0505 12:55:22.783352       1 controller.go:811] Starting provisioner controller rbd.csi.ceph.com_ceph-csi-rbd-provisioner-6f6497f79c-xnjqt_fdb4d17c-9a28-41a2-b65e-451d45d6a491!
I0505 12:55:22.783414       1 volume_store.go:97] Starting save volume queue
I0505 12:55:22.783713       1 clone_controller.go:66] Starting CloningProtection controller
I0505 12:55:22.783803       1 clone_controller.go:82] Started CloningProtection controller
I0505 12:55:22.784252       1 reflector.go:219] Starting reflector *v1.PersistentVolume (15m0s) from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:845
I0505 12:55:22.784290       1 reflector.go:255] Listing and watching *v1.PersistentVolume from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:845
I0505 12:55:22.784385       1 reflector.go:219] Starting reflector *v1.StorageClass (15m0s) from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:848
I0505 12:55:22.784431       1 reflector.go:255] Listing and watching *v1.StorageClass from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:848
I0505 12:55:22.885671       1 shared_informer.go:270] caches populated
I0505 12:55:22.887751       1 controller.go:860] Started provisioner controller rbd.csi.ceph.com_ceph-csi-rbd-provisioner-6f6497f79c-xnjqt_fdb4d17c-9a28-41a2-b65e-451d45d6a491!
I0505 12:55:27.699693       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:55:32.709016       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:55:37.717076       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:55:42.724877       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:55:47.733425       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:55:52.744077       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:55:57.753323       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:56:02.762121       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:56:07.772326       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:56:12.780791       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:56:17.789080       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:56:22.799563       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:56:27.809221       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:56:32.819517       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:56:37.827571       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:56:42.837215       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:56:47.847442       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:56:52.855673       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:56:57.864088       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:57:02.872612       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:57:07.879986       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:57:12.889527       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:57:17.898370       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:57:22.909053       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:57:27.916692       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:57:32.923183       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:57:37.930965       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:57:42.938858       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:57:47.945393       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:57:52.954957       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:57:57.966694       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:58:02.979079       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:58:07.988437       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:58:13.003558       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:58:18.011040       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:58:23.020252       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:58:24.180594       1 controller.go:1337] provision "default/test-pvc" class "general": started
I0505 12:58:24.180672       1 controller.go:528] skip translation of storage class for plugin: rbd.csi.ceph.com
I0505 12:58:24.180834       1 event.go:285] Event(v1.ObjectReference{Kind:"PersistentVolumeClaim", Namespace:"default", Name:"test-pvc", UID:"cd218fa2-ca30-4d16-953e-b8aa61020b1f", APIVersion:"v1", ResourceVersion:"2230", FieldPath:""}): type: 'Normal' reason: 'Provisioning' External provisioner is provisioning volume for claim "default/test-pvc"
I0505 12:58:24.184570       1 connection.go:183] GRPC call: /csi.v1.Controller/CreateVolume
I0505 12:58:24.184602       1 connection.go:184] GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-cd218fa2-ca30-4d16-953e-b8aa61020b1f","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-cd218fa2-ca30-4d16-953e-b8aa61020b1f","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}}]}
I0505 12:58:26.074157       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-cd218fa2-ca30-4d16-953e-b8aa61020b1f","csi.storage.k8s.io/pvc/name":"test-pvc","csi.storage.k8s.io/pvc/namespace":"default","imageFeatures":"layering","imageName":"csi-vol-19dcb593-4882-11f1-a1eb-36b010aa1286","journalPool":"kube","pool":"kube","thickProvision":"false"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-19dcb593-4882-11f1-a1eb-36b010aa1286"}}
I0505 12:58:26.074400       1 connection.go:187] GRPC error: <nil>
I0505 12:58:26.074426       1 controller.go:774] create volume rep: {CapacityBytes:5368709120 VolumeId:0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-19dcb593-4882-11f1-a1eb-36b010aa1286 VolumeContext:map[clusterID:4837cbf8-4f90-4300-b3f6-726c9b9f89b4 csi.storage.k8s.io/pv/name:pvc-cd218fa2-ca30-4d16-953e-b8aa61020b1f csi.storage.k8s.io/pvc/name:test-pvc csi.storage.k8s.io/pvc/namespace:default imageFeatures:layering imageName:csi-vol-19dcb593-4882-11f1-a1eb-36b010aa1286 journalPool:kube pool:kube thickProvision:false] ContentSource:<nil> AccessibleTopology:[] XXX_NoUnkeyedLiteral:{} XXX_unrecognized:[] XXX_sizecache:0}
I0505 12:58:26.074480       1 controller.go:858] successfully created PV pvc-cd218fa2-ca30-4d16-953e-b8aa61020b1f for PVC test-pvc and csi volume name 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-19dcb593-4882-11f1-a1eb-36b010aa1286
I0505 12:58:26.074495       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-19dcb593-4882-11f1-a1eb-36b010aa1286,ReadOnly:false,FSType:ext4,VolumeAttributes:map[string]string{clusterID: 4837cbf8-4f90-4300-b3f6-726c9b9f89b4,csi.storage.k8s.io/pv/name: pvc-cd218fa2-ca30-4d16-953e-b8aa61020b1f,csi.storage.k8s.io/pvc/name: test-pvc,csi.storage.k8s.io/pvc/namespace: default,imageFeatures: layering,imageName: csi-vol-19dcb593-4882-11f1-a1eb-36b010aa1286,journalPool: kube,pool: kube,storage.kubernetes.io/csiProvisionerIdentity: 1777985722668-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,},}}
I0505 12:58:26.074655       1 controller.go:1442] provision "default/test-pvc" class "general": volume "pvc-cd218fa2-ca30-4d16-953e-b8aa61020b1f" provisioned
I0505 12:58:26.074669       1 controller.go:1455] provision "default/test-pvc" class "general": succeeded
I0505 12:58:26.074675       1 volume_store.go:154] Saving volume pvc-cd218fa2-ca30-4d16-953e-b8aa61020b1f
I0505 12:58:26.082566       1 volume_store.go:157] Volume pvc-cd218fa2-ca30-4d16-953e-b8aa61020b1f saved
I0505 12:58:26.082808       1 controller.go:1069] Claim processing succeeded, removing PVC cd218fa2-ca30-4d16-953e-b8aa61020b1f from claims in progress
I0505 12:58:26.083234       1 event.go:285] Event(v1.ObjectReference{Kind:"PersistentVolumeClaim", Namespace:"default", Name:"test-pvc", UID:"cd218fa2-ca30-4d16-953e-b8aa61020b1f", APIVersion:"v1", ResourceVersion:"2230", FieldPath:""}): type: 'Normal' reason: 'ProvisioningSucceeded' Successfully provisioned volume pvc-cd218fa2-ca30-4d16-953e-b8aa61020b1f
I0505 12:58:28.028851       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:58:33.037246       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:58:38.045188       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:58:43.055266       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:58:48.062265       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:58:53.071110       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:58:58.079695       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:59:03.087542       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:59:08.094233       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0505 12:59:13.106286       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com