I0407 13:00:32.001570       1 feature_gate.go:245] feature gates: &{map[]}
I0407 13:00:32.001614       1 csi-provisioner.go:139] Version: v3.1.0
I0407 13:00:32.001618       1 csi-provisioner.go:162] Building kube configs for running in cluster...
I0407 13:00:32.002051       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
W0407 13:00:42.002479       1 connection.go:173] Still connecting to unix:///csi/csi-provisioner.sock
W0407 13:00:52.002724       1 connection.go:173] Still connecting to unix:///csi/csi-provisioner.sock
I0407 13:00:53.899081       1 common.go:111] Probing CSI driver for readiness
I0407 13:00:53.899110       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0407 13:00:53.899118       1 connection.go:184] GRPC request: {}
I0407 13:00:53.908895       1 connection.go:186] GRPC response: {}
I0407 13:00:53.908961       1 connection.go:187] GRPC error: <nil>
I0407 13:00:53.908971       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo
I0407 13:00:53.908975       1 connection.go:184] GRPC request: {}
I0407 13:00:53.909823       1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0407 13:00:53.909917       1 connection.go:187] GRPC error: <nil>
I0407 13:00:53.909932       1 csi-provisioner.go:206] Detected CSI driver rbd.csi.ceph.com
I0407 13:00:53.909945       1 csi-provisioner.go:216] Supports migration from in-tree plugin: kubernetes.io/rbd
I0407 13:00:53.910194       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0407 13:00:53.911004       1 common.go:111] Probing CSI driver for readiness
I0407 13:00:53.911118       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0407 13:00:53.911189       1 connection.go:184] GRPC request: {}
I0407 13:00:53.912369       1 connection.go:186] GRPC response: {}
I0407 13:00:53.912390       1 connection.go:187] GRPC error: <nil>
I0407 13:00:53.912399       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0407 13:00:53.912402       1 connection.go:184] GRPC request: {}
I0407 13:00:53.916066       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0407 13:00:53.916223       1 connection.go:187] GRPC error: <nil>
I0407 13:00:53.916231       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0407 13:00:53.916235       1 connection.go:184] GRPC request: {}
I0407 13:00:53.917719       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0407 13:00:53.920671       1 connection.go:187] GRPC error: <nil>
I0407 13:00:53.920825       1 csi-provisioner.go:278] CSI driver does not support PUBLISH_UNPUBLISH_VOLUME, not watching VolumeAttachments
I0407 13:00:53.921397       1 controller.go:732] Using saving PVs to API server in background
I0407 13:00:53.921964       1 leaderelection.go:248] attempting to acquire leader lease kube-system/rbd-csi-ceph-com...
I0407 13:00:53.939114       1 leaderelection.go:258] successfully acquired lease kube-system/rbd-csi-ceph-com
I0407 13:00:53.939309       1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-6f6497f79c-gtsdr
I0407 13:00:53.939619       1 leader_election.go:205] became leader, starting
I0407 13:00:53.940030       1 reflector.go:219] Starting reflector *v1.PersistentVolumeClaim (15m0s) from k8s.io/client-go/informers/factory.go:134
I0407 13:00:53.940135       1 reflector.go:255] Listing and watching *v1.PersistentVolumeClaim from k8s.io/client-go/informers/factory.go:134
I0407 13:00:53.940358       1 reflector.go:219] Starting reflector *v1.StorageClass (1h0m0s) from k8s.io/client-go/informers/factory.go:134
I0407 13:00:53.940375       1 reflector.go:255] Listing and watching *v1.StorageClass from k8s.io/client-go/informers/factory.go:134
I0407 13:00:53.952541       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:00:54.040152       1 shared_informer.go:270] caches populated
I0407 13:00:54.040207       1 shared_informer.go:270] caches populated
I0407 13:00:54.040260       1 controller.go:811] Starting provisioner controller rbd.csi.ceph.com_ceph-csi-rbd-provisioner-6f6497f79c-gtsdr_466bc7b6-0acb-4e92-b194-49aa59272be0!
I0407 13:00:54.040418       1 volume_store.go:97] Starting save volume queue
I0407 13:00:54.040442       1 clone_controller.go:66] Starting CloningProtection controller
I0407 13:00:54.040763       1 clone_controller.go:82] Started CloningProtection controller
I0407 13:00:54.040757       1 reflector.go:219] Starting reflector *v1.StorageClass (15m0s) from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:848
I0407 13:00:54.040945       1 reflector.go:255] Listing and watching *v1.StorageClass from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:848
I0407 13:00:54.040767       1 reflector.go:219] Starting reflector *v1.PersistentVolume (15m0s) from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:845
I0407 13:00:54.041044       1 reflector.go:255] Listing and watching *v1.PersistentVolume from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:845
I0407 13:00:54.141149       1 shared_informer.go:270] caches populated
I0407 13:00:54.141650       1 controller.go:860] Started provisioner controller rbd.csi.ceph.com_ceph-csi-rbd-provisioner-6f6497f79c-gtsdr_466bc7b6-0acb-4e92-b194-49aa59272be0!
I0407 13:00:58.964423       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:01:03.974208       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:01:08.983548       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:01:13.995275       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:01:19.003879       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:01:24.012887       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:01:29.023378       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:01:34.032495       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:01:39.040398       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:01:44.052387       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:01:49.063431       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:01:54.072196       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:01:59.081251       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:02:04.091665       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:02:09.102177       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:02:14.114151       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:02:19.128428       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:02:24.137042       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:02:29.145395       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:02:34.155131       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:02:39.164574       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:02:44.175340       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:02:49.184083       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:02:54.191137       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:02:59.200965       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:03:04.214501       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:03:09.225600       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:03:14.236315       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:03:19.251130       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:03:24.264579       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:03:29.271788       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:03:34.280405       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:03:39.299803       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:03:44.309580       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:03:49.319826       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:03:54.330469       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:03:59.340319       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:04:00.266761       1 controller.go:1337] provision "default/test-pvc" class "general": started
I0407 13:04:00.267056       1 event.go:285] Event(v1.ObjectReference{Kind:"PersistentVolumeClaim", Namespace:"default", Name:"test-pvc", UID:"9aa58f30-56fc-4929-8c8a-a13cd8319a67", APIVersion:"v1", ResourceVersion:"2216", FieldPath:""}): type: 'Normal' reason: 'Provisioning' External provisioner is provisioning volume for claim "default/test-pvc"
I0407 13:04:00.267986       1 controller.go:528] skip translation of storage class for plugin: rbd.csi.ceph.com
I0407 13:04:00.272015       1 connection.go:183] GRPC call: /csi.v1.Controller/CreateVolume
I0407 13:04:00.272040       1 connection.go:184] GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-9aa58f30-56fc-4929-8c8a-a13cd8319a67","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-9aa58f30-56fc-4929-8c8a-a13cd8319a67","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}}]}
I0407 13:04:02.091287       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-9aa58f30-56fc-4929-8c8a-a13cd8319a67","csi.storage.k8s.io/pvc/name":"test-pvc","csi.storage.k8s.io/pvc/namespace":"default","imageFeatures":"layering","imageName":"csi-vol-3e9f2553-3282-11f1-884d-4ed6b5c3b57a","journalPool":"kube","pool":"kube","thickProvision":"false"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-3e9f2553-3282-11f1-884d-4ed6b5c3b57a"}}
I0407 13:04:02.091563       1 connection.go:187] GRPC error: <nil>
I0407 13:04:02.091579       1 controller.go:774] create volume rep: {CapacityBytes:5368709120 VolumeId:0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-3e9f2553-3282-11f1-884d-4ed6b5c3b57a VolumeContext:map[clusterID:4837cbf8-4f90-4300-b3f6-726c9b9f89b4 csi.storage.k8s.io/pv/name:pvc-9aa58f30-56fc-4929-8c8a-a13cd8319a67 csi.storage.k8s.io/pvc/name:test-pvc csi.storage.k8s.io/pvc/namespace:default imageFeatures:layering imageName:csi-vol-3e9f2553-3282-11f1-884d-4ed6b5c3b57a journalPool:kube pool:kube thickProvision:false] ContentSource:<nil> AccessibleTopology:[] XXX_NoUnkeyedLiteral:{} XXX_unrecognized:[] XXX_sizecache:0}
I0407 13:04:02.091654       1 controller.go:858] successfully created PV pvc-9aa58f30-56fc-4929-8c8a-a13cd8319a67 for PVC test-pvc and csi volume name 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-3e9f2553-3282-11f1-884d-4ed6b5c3b57a
I0407 13:04:02.091673       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-3e9f2553-3282-11f1-884d-4ed6b5c3b57a,ReadOnly:false,FSType:ext4,VolumeAttributes:map[string]string{clusterID: 4837cbf8-4f90-4300-b3f6-726c9b9f89b4,csi.storage.k8s.io/pv/name: pvc-9aa58f30-56fc-4929-8c8a-a13cd8319a67,csi.storage.k8s.io/pvc/name: test-pvc,csi.storage.k8s.io/pvc/namespace: default,imageFeatures: layering,imageName: csi-vol-3e9f2553-3282-11f1-884d-4ed6b5c3b57a,journalPool: kube,pool: kube,storage.kubernetes.io/csiProvisionerIdentity: 1775566853920-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,},}}
I0407 13:04:02.091911       1 controller.go:1442] provision "default/test-pvc" class "general": volume "pvc-9aa58f30-56fc-4929-8c8a-a13cd8319a67" provisioned
I0407 13:04:02.091929       1 controller.go:1455] provision "default/test-pvc" class "general": succeeded
I0407 13:04:02.091938       1 volume_store.go:154] Saving volume pvc-9aa58f30-56fc-4929-8c8a-a13cd8319a67
I0407 13:04:02.105422       1 volume_store.go:157] Volume pvc-9aa58f30-56fc-4929-8c8a-a13cd8319a67 saved
I0407 13:04:02.105503       1 controller.go:1069] Claim processing succeeded, removing PVC 9aa58f30-56fc-4929-8c8a-a13cd8319a67 from claims in progress
I0407 13:04:02.106064       1 event.go:285] Event(v1.ObjectReference{Kind:"PersistentVolumeClaim", Namespace:"default", Name:"test-pvc", UID:"9aa58f30-56fc-4929-8c8a-a13cd8319a67", APIVersion:"v1", ResourceVersion:"2216", FieldPath:""}): type: 'Normal' reason: 'ProvisioningSucceeded' Successfully provisioned volume pvc-9aa58f30-56fc-4929-8c8a-a13cd8319a67
I0407 13:04:04.352086       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:04:09.365541       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:04:14.378937       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:04:19.388923       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:04:24.402499       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:04:29.413711       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:04:34.423613       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:04:39.431713       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:04:44.440294       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:04:49.450091       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0407 13:04:54.457638       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com