I0714 17:02:29.827034       1 feature_gate.go:245] feature gates: &{map[]}
I0714 17:02:29.827087       1 csi-provisioner.go:139] Version: v3.1.0
I0714 17:02:29.827091       1 csi-provisioner.go:162] Building kube configs for running in cluster...
I0714 17:02:29.827645       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
W0714 17:02:39.828082       1 connection.go:173] Still connecting to unix:///csi/csi-provisioner.sock
W0714 17:02:49.827952       1 connection.go:173] Still connecting to unix:///csi/csi-provisioner.sock
I0714 17:02:53.066065       1 common.go:111] Probing CSI driver for readiness
I0714 17:02:53.066092       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0714 17:02:53.066096       1 connection.go:184] GRPC request: {}
I0714 17:02:53.070518       1 connection.go:186] GRPC response: {}
I0714 17:02:53.070599       1 connection.go:187] GRPC error: <nil>
I0714 17:02:53.070634       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo
I0714 17:02:53.070641       1 connection.go:184] GRPC request: {}
I0714 17:02:53.074861       1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0714 17:02:53.074951       1 connection.go:187] GRPC error: <nil>
I0714 17:02:53.074965       1 csi-provisioner.go:206] Detected CSI driver rbd.csi.ceph.com
I0714 17:02:53.074977       1 csi-provisioner.go:216] Supports migration from in-tree plugin: kubernetes.io/rbd
I0714 17:02:53.075052       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0714 17:02:53.076343       1 common.go:111] Probing CSI driver for readiness
I0714 17:02:53.076393       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0714 17:02:53.076526       1 connection.go:184] GRPC request: {}
I0714 17:02:53.077118       1 connection.go:186] GRPC response: {}
I0714 17:02:53.077153       1 connection.go:187] GRPC error: <nil>
I0714 17:02:53.077165       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0714 17:02:53.077171       1 connection.go:184] GRPC request: {}
I0714 17:02:53.078310       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0714 17:02:53.080737       1 connection.go:187] GRPC error: <nil>
I0714 17:02:53.080748       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0714 17:02:53.080753       1 connection.go:184] GRPC request: {}
I0714 17:02:53.081356       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0714 17:02:53.081479       1 connection.go:187] GRPC error: <nil>
I0714 17:02:53.081531       1 csi-provisioner.go:278] CSI driver does not support PUBLISH_UNPUBLISH_VOLUME, not watching VolumeAttachments
I0714 17:02:53.082034       1 controller.go:732] Using saving PVs to API server in background
I0714 17:02:53.082424       1 leaderelection.go:248] attempting to acquire leader lease kube-system/rbd-csi-ceph-com...
I0714 17:02:53.096276       1 leaderelection.go:258] successfully acquired lease kube-system/rbd-csi-ceph-com
I0714 17:02:53.096433       1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-6f6497f79c-gl64m
I0714 17:02:53.096799       1 leader_election.go:205] became leader, starting
I0714 17:02:53.096986       1 reflector.go:219] Starting reflector *v1.StorageClass (1h0m0s) from k8s.io/client-go/informers/factory.go:134
I0714 17:02:53.096993       1 reflector.go:255] Listing and watching *v1.StorageClass from k8s.io/client-go/informers/factory.go:134
I0714 17:02:53.097284       1 reflector.go:219] Starting reflector *v1.PersistentVolumeClaim (15m0s) from k8s.io/client-go/informers/factory.go:134
I0714 17:02:53.097290       1 reflector.go:255] Listing and watching *v1.PersistentVolumeClaim from k8s.io/client-go/informers/factory.go:134
I0714 17:02:53.105123       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:02:53.197429       1 shared_informer.go:270] caches populated
I0714 17:02:53.197478       1 shared_informer.go:270] caches populated
I0714 17:02:53.197496       1 controller.go:811] Starting provisioner controller rbd.csi.ceph.com_ceph-csi-rbd-provisioner-6f6497f79c-gl64m_468cb1e3-3580-4059-9b9d-a66cdb23a879!
I0714 17:02:53.197592       1 clone_controller.go:66] Starting CloningProtection controller
I0714 17:02:53.197635       1 clone_controller.go:82] Started CloningProtection controller
I0714 17:02:53.197658       1 volume_store.go:97] Starting save volume queue
I0714 17:02:53.197865       1 reflector.go:219] Starting reflector *v1.PersistentVolume (15m0s) from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:845
I0714 17:02:53.197885       1 reflector.go:255] Listing and watching *v1.PersistentVolume from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:845
I0714 17:02:53.198051       1 reflector.go:219] Starting reflector *v1.StorageClass (15m0s) from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:848
I0714 17:02:53.198079       1 reflector.go:255] Listing and watching *v1.StorageClass from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:848
I0714 17:02:53.298697       1 shared_informer.go:270] caches populated
I0714 17:02:53.300641       1 controller.go:860] Started provisioner controller rbd.csi.ceph.com_ceph-csi-rbd-provisioner-6f6497f79c-gl64m_468cb1e3-3580-4059-9b9d-a66cdb23a879!
I0714 17:02:58.113710       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:03:03.124103       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:03:08.134228       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:03:13.142642       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:03:18.151799       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:03:23.158638       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:03:28.169481       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:03:33.175859       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:03:38.185541       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:03:43.195596       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:03:48.205754       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:03:53.215921       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:03:58.224472       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:04:03.234131       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:04:08.246032       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:04:13.253480       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:04:18.264105       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:04:23.276048       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:04:28.286756       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:04:33.296408       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:04:38.304268       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:04:43.313729       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:04:48.322388       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:04:53.332347       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:04:58.340855       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:05:03.350865       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:05:08.359507       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:05:13.372004       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:05:18.379794       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:05:23.395619       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:05:28.404859       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:05:33.414680       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:05:38.422577       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:05:43.432655       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:05:48.444444       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:05:53.453378       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:05:58.461559       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:06:03.469116       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:06:08.477657       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:06:13.488307       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:06:18.495768       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:06:23.504577       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:06:28.512881       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:06:33.522285       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:06:38.532181       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:06:43.541001       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:06:48.551003       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:06:53.557921       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:06:58.565752       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:07:03.573410       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:07:03.801243       1 controller.go:1337] provision "default/test-pvc" class "general": started
I0714 17:07:03.801297       1 controller.go:528] skip translation of storage class for plugin: rbd.csi.ceph.com
I0714 17:07:03.801637       1 event.go:285] Event(v1.ObjectReference{Kind:"PersistentVolumeClaim", Namespace:"default", Name:"test-pvc", UID:"44064e29-16b6-4487-ae35-cca195bca86b", APIVersion:"v1", ResourceVersion:"2618", FieldPath:""}): type: 'Normal' reason: 'Provisioning' External provisioner is provisioning volume for claim "default/test-pvc"
I0714 17:07:03.804388       1 connection.go:183] GRPC call: /csi.v1.Controller/CreateVolume
I0714 17:07:03.804404       1 connection.go:184] GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-44064e29-16b6-4487-ae35-cca195bca86b","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-44064e29-16b6-4487-ae35-cca195bca86b","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}}]}
I0714 17:07:03.908048       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-44064e29-16b6-4487-ae35-cca195bca86b","csi.storage.k8s.io/pvc/name":"test-pvc","csi.storage.k8s.io/pvc/namespace":"default","imageFeatures":"layering","imageName":"csi-vol-6f8ff1f4-7fa6-11f1-bda2-a211fcbb3140","journalPool":"kube","pool":"kube","thickProvision":"false"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-6f8ff1f4-7fa6-11f1-bda2-a211fcbb3140"}}
I0714 17:07:03.908344       1 connection.go:187] GRPC error: <nil>
I0714 17:07:03.908360       1 controller.go:774] create volume rep: {CapacityBytes:5368709120 VolumeId:0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-6f8ff1f4-7fa6-11f1-bda2-a211fcbb3140 VolumeContext:map[clusterID:4837cbf8-4f90-4300-b3f6-726c9b9f89b4 csi.storage.k8s.io/pv/name:pvc-44064e29-16b6-4487-ae35-cca195bca86b csi.storage.k8s.io/pvc/name:test-pvc csi.storage.k8s.io/pvc/namespace:default imageFeatures:layering imageName:csi-vol-6f8ff1f4-7fa6-11f1-bda2-a211fcbb3140 journalPool:kube pool:kube thickProvision:false] ContentSource:<nil> AccessibleTopology:[] XXX_NoUnkeyedLiteral:{} XXX_unrecognized:[] XXX_sizecache:0}
I0714 17:07:03.908457       1 controller.go:858] successfully created PV pvc-44064e29-16b6-4487-ae35-cca195bca86b for PVC test-pvc and csi volume name 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-6f8ff1f4-7fa6-11f1-bda2-a211fcbb3140
I0714 17:07:03.908486       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-6f8ff1f4-7fa6-11f1-bda2-a211fcbb3140,ReadOnly:false,FSType:ext4,VolumeAttributes:map[string]string{clusterID: 4837cbf8-4f90-4300-b3f6-726c9b9f89b4,csi.storage.k8s.io/pv/name: pvc-44064e29-16b6-4487-ae35-cca195bca86b,csi.storage.k8s.io/pvc/name: test-pvc,csi.storage.k8s.io/pvc/namespace: default,imageFeatures: layering,imageName: csi-vol-6f8ff1f4-7fa6-11f1-bda2-a211fcbb3140,journalPool: kube,pool: kube,storage.kubernetes.io/csiProvisionerIdentity: 1784048573081-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,},}}
I0714 17:07:03.908742       1 controller.go:1442] provision "default/test-pvc" class "general": volume "pvc-44064e29-16b6-4487-ae35-cca195bca86b" provisioned
I0714 17:07:03.908761       1 controller.go:1455] provision "default/test-pvc" class "general": succeeded
I0714 17:07:03.908769       1 volume_store.go:154] Saving volume pvc-44064e29-16b6-4487-ae35-cca195bca86b
I0714 17:07:03.916808       1 volume_store.go:157] Volume pvc-44064e29-16b6-4487-ae35-cca195bca86b saved
I0714 17:07:03.916868       1 controller.go:1069] Claim processing succeeded, removing PVC 44064e29-16b6-4487-ae35-cca195bca86b from claims in progress
I0714 17:07:03.917079       1 event.go:285] Event(v1.ObjectReference{Kind:"PersistentVolumeClaim", Namespace:"default", Name:"test-pvc", UID:"44064e29-16b6-4487-ae35-cca195bca86b", APIVersion:"v1", ResourceVersion:"2618", FieldPath:""}): type: 'Normal' reason: 'ProvisioningSucceeded' Successfully provisioned volume pvc-44064e29-16b6-4487-ae35-cca195bca86b
I0714 17:07:08.582545       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:07:13.597674       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:07:18.606417       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:07:23.615154       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:07:28.622678       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:07:33.630412       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:07:38.638287       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:07:43.649411       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:07:48.659538       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0714 17:07:53.669458       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com