I0211 02:28:59.503032       1 feature_gate.go:245] feature gates: &{map[]}
I0211 02:28:59.503357       1 csi-provisioner.go:139] Version: v3.1.0
I0211 02:28:59.503425       1 csi-provisioner.go:162] Building kube configs for running in cluster...
I0211 02:28:59.504119       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
W0211 02:29:09.505002       1 connection.go:173] Still connecting to unix:///csi/csi-provisioner.sock
W0211 02:29:19.504275       1 connection.go:173] Still connecting to unix:///csi/csi-provisioner.sock
W0211 02:29:29.504526       1 connection.go:173] Still connecting to unix:///csi/csi-provisioner.sock
I0211 02:29:33.149973       1 common.go:111] Probing CSI driver for readiness
I0211 02:29:33.149993       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0211 02:29:33.149997       1 connection.go:184] GRPC request: {}
I0211 02:29:33.168731       1 connection.go:186] GRPC response: {}
I0211 02:29:33.168790       1 connection.go:187] GRPC error: <nil>
I0211 02:29:33.168801       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo
I0211 02:29:33.168806       1 connection.go:184] GRPC request: {}
I0211 02:29:33.175680       1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0211 02:29:33.179543       1 connection.go:187] GRPC error: <nil>
I0211 02:29:33.179561       1 csi-provisioner.go:206] Detected CSI driver rbd.csi.ceph.com
I0211 02:29:33.179568       1 csi-provisioner.go:216] Supports migration from in-tree plugin: kubernetes.io/rbd
I0211 02:29:33.181529       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0211 02:29:33.194721       1 common.go:111] Probing CSI driver for readiness
I0211 02:29:33.194748       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0211 02:29:33.194753       1 connection.go:184] GRPC request: {}
I0211 02:29:33.199497       1 connection.go:186] GRPC response: {}
I0211 02:29:33.199608       1 connection.go:187] GRPC error: <nil>
I0211 02:29:33.199744       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0211 02:29:33.199816       1 connection.go:184] GRPC request: {}
I0211 02:29:33.203546       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0211 02:29:33.204763       1 connection.go:187] GRPC error: <nil>
I0211 02:29:33.204798       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0211 02:29:33.204846       1 connection.go:184] GRPC request: {}
I0211 02:29:33.207882       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0211 02:29:33.208718       1 connection.go:187] GRPC error: <nil>
I0211 02:29:33.208831       1 csi-provisioner.go:278] CSI driver does not support PUBLISH_UNPUBLISH_VOLUME, not watching VolumeAttachments
I0211 02:29:33.210208       1 controller.go:732] Using saving PVs to API server in background
I0211 02:29:33.210630       1 leaderelection.go:248] attempting to acquire leader lease kube-system/rbd-csi-ceph-com...
I0211 02:29:33.236419       1 leaderelection.go:258] successfully acquired lease kube-system/rbd-csi-ceph-com
I0211 02:29:33.238996       1 leader_election.go:205] became leader, starting
I0211 02:29:33.236529       1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-5d8ff44fc9-v9dvl
I0211 02:29:33.239325       1 reflector.go:219] Starting reflector *v1.StorageClass (1h0m0s) from k8s.io/client-go/informers/factory.go:134
I0211 02:29:33.239429       1 reflector.go:255] Listing and watching *v1.StorageClass from k8s.io/client-go/informers/factory.go:134
I0211 02:29:33.239724       1 reflector.go:219] Starting reflector *v1.PersistentVolumeClaim (15m0s) from k8s.io/client-go/informers/factory.go:134
I0211 02:29:33.239750       1 reflector.go:255] Listing and watching *v1.PersistentVolumeClaim from k8s.io/client-go/informers/factory.go:134
I0211 02:29:33.256022       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:29:33.340108       1 shared_informer.go:270] caches populated
I0211 02:29:33.340277       1 shared_informer.go:270] caches populated
I0211 02:29:33.340321       1 controller.go:811] Starting provisioner controller rbd.csi.ceph.com_ceph-csi-rbd-provisioner-5d8ff44fc9-v9dvl_76dec9c5-06f9-4e53-ac7f-8c157f36c4b2!
I0211 02:29:33.340411       1 clone_controller.go:66] Starting CloningProtection controller
I0211 02:29:33.340487       1 clone_controller.go:82] Started CloningProtection controller
I0211 02:29:33.340553       1 volume_store.go:97] Starting save volume queue
I0211 02:29:33.340855       1 reflector.go:219] Starting reflector *v1.PersistentVolume (15m0s) from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:845
I0211 02:29:33.340916       1 reflector.go:255] Listing and watching *v1.PersistentVolume from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:845
I0211 02:29:33.342376       1 reflector.go:219] Starting reflector *v1.StorageClass (15m0s) from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:848
I0211 02:29:33.342445       1 reflector.go:255] Listing and watching *v1.StorageClass from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:848
I0211 02:29:33.441238       1 shared_informer.go:270] caches populated
I0211 02:29:33.441530       1 controller.go:860] Started provisioner controller rbd.csi.ceph.com_ceph-csi-rbd-provisioner-5d8ff44fc9-v9dvl_76dec9c5-06f9-4e53-ac7f-8c157f36c4b2!
I0211 02:29:38.266690       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:29:43.275107       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:29:48.284315       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:29:53.292239       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:29:58.298753       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:30:03.307140       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:30:08.315292       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:30:13.359614       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:30:18.372543       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:30:23.380286       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:30:28.391029       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:30:33.401021       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:30:38.424546       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:30:43.433870       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:30:48.449833       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:30:53.459911       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:30:58.468926       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:31:03.475328       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:31:08.488469       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:31:13.527928       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:31:18.595902       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:31:23.604384       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:31:28.614794       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:31:33.620861       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:31:38.628131       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:31:43.634177       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:31:48.642180       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:31:53.656817       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:31:58.665193       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:32:03.671404       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:32:08.709310       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:32:13.722441       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:32:18.735506       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:32:23.754826       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:32:28.764332       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:32:33.857117       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:32:38.864207       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:32:40.980571       1 controller.go:1337] provision "default/test-pvc" class "general": started
I0211 02:32:40.980801       1 controller.go:528] skip translation of storage class for plugin: rbd.csi.ceph.com
I0211 02:32:40.986887       1 event.go:285] Event(v1.ObjectReference{Kind:"PersistentVolumeClaim", Namespace:"default", Name:"test-pvc", UID:"71982379-08e8-4fea-aa5d-17c41b7fb455", APIVersion:"v1", ResourceVersion:"1860", FieldPath:""}): type: 'Normal' reason: 'Provisioning' External provisioner is provisioning volume for claim "default/test-pvc"
I0211 02:32:40.987492       1 connection.go:183] GRPC call: /csi.v1.Controller/CreateVolume
I0211 02:32:40.987502       1 connection.go:184] GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-71982379-08e8-4fea-aa5d-17c41b7fb455","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-71982379-08e8-4fea-aa5d-17c41b7fb455","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}}]}
I0211 02:32:42.317440       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-71982379-08e8-4fea-aa5d-17c41b7fb455","csi.storage.k8s.io/pvc/name":"test-pvc","csi.storage.k8s.io/pvc/namespace":"default","imageFeatures":"layering","imageName":"csi-vol-f01d1523-06f1-11f1-a30e-46c3ce0a08d8","journalPool":"kube","pool":"kube","thickProvision":"false"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-f01d1523-06f1-11f1-a30e-46c3ce0a08d8"}}
I0211 02:32:42.317586       1 connection.go:187] GRPC error: <nil>
I0211 02:32:42.317597       1 controller.go:774] create volume rep: {CapacityBytes:5368709120 VolumeId:0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-f01d1523-06f1-11f1-a30e-46c3ce0a08d8 VolumeContext:map[clusterID:4837cbf8-4f90-4300-b3f6-726c9b9f89b4 csi.storage.k8s.io/pv/name:pvc-71982379-08e8-4fea-aa5d-17c41b7fb455 csi.storage.k8s.io/pvc/name:test-pvc csi.storage.k8s.io/pvc/namespace:default imageFeatures:layering imageName:csi-vol-f01d1523-06f1-11f1-a30e-46c3ce0a08d8 journalPool:kube pool:kube thickProvision:false] ContentSource:<nil> AccessibleTopology:[] XXX_NoUnkeyedLiteral:{} XXX_unrecognized:[] XXX_sizecache:0}
I0211 02:32:42.317652       1 controller.go:858] successfully created PV pvc-71982379-08e8-4fea-aa5d-17c41b7fb455 for PVC test-pvc and csi volume name 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-f01d1523-06f1-11f1-a30e-46c3ce0a08d8
I0211 02:32:42.317664       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-f01d1523-06f1-11f1-a30e-46c3ce0a08d8,ReadOnly:false,FSType:ext4,VolumeAttributes:map[string]string{clusterID: 4837cbf8-4f90-4300-b3f6-726c9b9f89b4,csi.storage.k8s.io/pv/name: pvc-71982379-08e8-4fea-aa5d-17c41b7fb455,csi.storage.k8s.io/pvc/name: test-pvc,csi.storage.k8s.io/pvc/namespace: default,imageFeatures: layering,imageName: csi-vol-f01d1523-06f1-11f1-a30e-46c3ce0a08d8,journalPool: kube,pool: kube,storage.kubernetes.io/csiProvisionerIdentity: 1770776973208-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,},}}
I0211 02:32:42.319377       1 controller.go:1442] provision "default/test-pvc" class "general": volume "pvc-71982379-08e8-4fea-aa5d-17c41b7fb455" provisioned
I0211 02:32:42.319402       1 controller.go:1455] provision "default/test-pvc" class "general": succeeded
I0211 02:32:42.319412       1 volume_store.go:154] Saving volume pvc-71982379-08e8-4fea-aa5d-17c41b7fb455
I0211 02:32:42.340348       1 volume_store.go:157] Volume pvc-71982379-08e8-4fea-aa5d-17c41b7fb455 saved
I0211 02:32:42.340982       1 controller.go:1069] Claim processing succeeded, removing PVC 71982379-08e8-4fea-aa5d-17c41b7fb455 from claims in progress
I0211 02:32:42.342232       1 event.go:285] Event(v1.ObjectReference{Kind:"PersistentVolumeClaim", Namespace:"default", Name:"test-pvc", UID:"71982379-08e8-4fea-aa5d-17c41b7fb455", APIVersion:"v1", ResourceVersion:"1860", FieldPath:""}): type: 'Normal' reason: 'ProvisioningSucceeded' Successfully provisioned volume pvc-71982379-08e8-4fea-aa5d-17c41b7fb455
I0211 02:32:43.874833       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:32:48.890409       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:32:53.903615       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:32:58.986398       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:33:04.225928       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:33:09.232374       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:33:14.240351       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:33:19.248841       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:33:24.258781       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:33:29.267124       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:33:34.272313       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:33:39.281997       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 02:33:44.293118       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com