I0402 22:33:44.255502       1 feature_gate.go:245] feature gates: &{map[]}
I0402 22:33:44.255598       1 csi-provisioner.go:139] Version: v3.1.0
I0402 22:33:44.255615       1 csi-provisioner.go:162] Building kube configs for running in cluster...
I0402 22:33:44.256087       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
W0402 22:33:54.256562       1 connection.go:173] Still connecting to unix:///csi/csi-provisioner.sock
I0402 22:34:03.823866       1 common.go:111] Probing CSI driver for readiness
I0402 22:34:03.823903       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0402 22:34:03.823986       1 connection.go:184] GRPC request: {}
I0402 22:34:03.836829       1 connection.go:186] GRPC response: {}
I0402 22:34:03.836982       1 connection.go:187] GRPC error: <nil>
I0402 22:34:03.836998       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo
I0402 22:34:03.837005       1 connection.go:184] GRPC request: {}
I0402 22:34:03.838073       1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0402 22:34:03.838198       1 connection.go:187] GRPC error: <nil>
I0402 22:34:03.838246       1 csi-provisioner.go:206] Detected CSI driver rbd.csi.ceph.com
I0402 22:34:03.838266       1 csi-provisioner.go:216] Supports migration from in-tree plugin: kubernetes.io/rbd
I0402 22:34:03.838452       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0402 22:34:03.839475       1 common.go:111] Probing CSI driver for readiness
I0402 22:34:03.839489       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0402 22:34:03.839492       1 connection.go:184] GRPC request: {}
I0402 22:34:03.840141       1 connection.go:186] GRPC response: {}
I0402 22:34:03.840212       1 connection.go:187] GRPC error: <nil>
I0402 22:34:03.840233       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0402 22:34:03.840243       1 connection.go:184] GRPC request: {}
I0402 22:34:03.842797       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0402 22:34:03.842913       1 connection.go:187] GRPC error: <nil>
I0402 22:34:03.842920       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0402 22:34:03.842923       1 connection.go:184] GRPC request: {}
I0402 22:34:03.844777       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0402 22:34:03.844858       1 connection.go:187] GRPC error: <nil>
I0402 22:34:03.844902       1 csi-provisioner.go:278] CSI driver does not support PUBLISH_UNPUBLISH_VOLUME, not watching VolumeAttachments
I0402 22:34:03.845901       1 controller.go:732] Using saving PVs to API server in background
I0402 22:34:03.846797       1 leaderelection.go:248] attempting to acquire leader lease kube-system/rbd-csi-ceph-com...
I0402 22:34:03.857681       1 leaderelection.go:258] successfully acquired lease kube-system/rbd-csi-ceph-com
I0402 22:34:03.857736       1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-6f6497f79c-qgjrg
I0402 22:34:03.857785       1 leader_election.go:205] became leader, starting
I0402 22:34:03.858103       1 reflector.go:219] Starting reflector *v1.StorageClass (1h0m0s) from k8s.io/client-go/informers/factory.go:134
I0402 22:34:03.858122       1 reflector.go:255] Listing and watching *v1.StorageClass from k8s.io/client-go/informers/factory.go:134
I0402 22:34:03.858515       1 reflector.go:219] Starting reflector *v1.PersistentVolumeClaim (15m0s) from k8s.io/client-go/informers/factory.go:134
I0402 22:34:03.858531       1 reflector.go:255] Listing and watching *v1.PersistentVolumeClaim from k8s.io/client-go/informers/factory.go:134
I0402 22:34:03.864889       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:34:03.959115       1 shared_informer.go:270] caches populated
I0402 22:34:03.959191       1 shared_informer.go:270] caches populated
I0402 22:34:03.959215       1 controller.go:811] Starting provisioner controller rbd.csi.ceph.com_ceph-csi-rbd-provisioner-6f6497f79c-qgjrg_fb79c2b9-160a-4d60-ab94-93f701af5216!
I0402 22:34:03.959294       1 clone_controller.go:66] Starting CloningProtection controller
I0402 22:34:03.959350       1 clone_controller.go:82] Started CloningProtection controller
I0402 22:34:03.959417       1 volume_store.go:97] Starting save volume queue
I0402 22:34:03.959920       1 reflector.go:219] Starting reflector *v1.PersistentVolume (15m0s) from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:845
I0402 22:34:03.959943       1 reflector.go:255] Listing and watching *v1.PersistentVolume from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:845
I0402 22:34:03.959874       1 reflector.go:219] Starting reflector *v1.StorageClass (15m0s) from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:848
I0402 22:34:03.959985       1 reflector.go:255] Listing and watching *v1.StorageClass from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:848
I0402 22:34:04.060328       1 shared_informer.go:270] caches populated
I0402 22:34:04.061318       1 controller.go:860] Started provisioner controller rbd.csi.ceph.com_ceph-csi-rbd-provisioner-6f6497f79c-qgjrg_fb79c2b9-160a-4d60-ab94-93f701af5216!
I0402 22:34:08.965677       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:34:13.976324       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:34:18.985683       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:34:23.997891       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:34:29.008002       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:34:34.018428       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:34:39.027430       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:34:44.035147       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:34:49.044360       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:34:54.053924       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:34:59.062691       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:35:04.072675       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:35:09.083661       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:35:14.094774       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:35:19.102428       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:35:24.111320       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:35:29.118725       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:35:34.127826       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:35:39.137107       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:35:44.145965       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:35:49.156868       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:35:54.166629       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:35:59.173530       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:36:04.188716       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:36:09.203768       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:36:14.215465       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:36:19.225124       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:36:24.233670       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:36:29.242146       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:36:34.249903       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:36:39.256978       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:36:44.270624       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:36:49.281925       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:36:54.292769       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:36:59.301609       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:37:04.313020       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:37:09.327140       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:37:14.337059       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:37:19.345403       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:37:19.441057       1 controller.go:1337] provision "default/test-pvc" class "general": started
I0402 22:37:19.441160       1 controller.go:528] skip translation of storage class for plugin: rbd.csi.ceph.com
I0402 22:37:19.441984       1 event.go:285] Event(v1.ObjectReference{Kind:"PersistentVolumeClaim", Namespace:"default", Name:"test-pvc", UID:"83af9eed-64fc-493f-bde4-17354f7b7887", APIVersion:"v1", ResourceVersion:"2358", FieldPath:""}): type: 'Normal' reason: 'Provisioning' External provisioner is provisioning volume for claim "default/test-pvc"
I0402 22:37:19.446361       1 connection.go:183] GRPC call: /csi.v1.Controller/CreateVolume
I0402 22:37:19.446402       1 connection.go:184] GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-83af9eed-64fc-493f-bde4-17354f7b7887","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-83af9eed-64fc-493f-bde4-17354f7b7887","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}}]}
I0402 22:37:20.625118       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-83af9eed-64fc-493f-bde4-17354f7b7887","csi.storage.k8s.io/pvc/name":"test-pvc","csi.storage.k8s.io/pvc/namespace":"default","imageFeatures":"layering","imageName":"csi-vol-820fe7da-2ee4-11f1-845b-1ab46ff150a8","journalPool":"kube","pool":"kube","thickProvision":"false"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-820fe7da-2ee4-11f1-845b-1ab46ff150a8"}}
I0402 22:37:20.625276       1 connection.go:187] GRPC error: <nil>
I0402 22:37:20.625286       1 controller.go:774] create volume rep: {CapacityBytes:5368709120 VolumeId:0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-820fe7da-2ee4-11f1-845b-1ab46ff150a8 VolumeContext:map[clusterID:4837cbf8-4f90-4300-b3f6-726c9b9f89b4 csi.storage.k8s.io/pv/name:pvc-83af9eed-64fc-493f-bde4-17354f7b7887 csi.storage.k8s.io/pvc/name:test-pvc csi.storage.k8s.io/pvc/namespace:default imageFeatures:layering imageName:csi-vol-820fe7da-2ee4-11f1-845b-1ab46ff150a8 journalPool:kube pool:kube thickProvision:false] ContentSource:<nil> AccessibleTopology:[] XXX_NoUnkeyedLiteral:{} XXX_unrecognized:[] XXX_sizecache:0}
I0402 22:37:20.625356       1 controller.go:858] successfully created PV pvc-83af9eed-64fc-493f-bde4-17354f7b7887 for PVC test-pvc and csi volume name 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-820fe7da-2ee4-11f1-845b-1ab46ff150a8
I0402 22:37:20.625367       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-820fe7da-2ee4-11f1-845b-1ab46ff150a8,ReadOnly:false,FSType:ext4,VolumeAttributes:map[string]string{clusterID: 4837cbf8-4f90-4300-b3f6-726c9b9f89b4,csi.storage.k8s.io/pv/name: pvc-83af9eed-64fc-493f-bde4-17354f7b7887,csi.storage.k8s.io/pvc/name: test-pvc,csi.storage.k8s.io/pvc/namespace: default,imageFeatures: layering,imageName: csi-vol-820fe7da-2ee4-11f1-845b-1ab46ff150a8,journalPool: kube,pool: kube,storage.kubernetes.io/csiProvisionerIdentity: 1775169243844-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,},}}
I0402 22:37:20.625526       1 controller.go:1442] provision "default/test-pvc" class "general": volume "pvc-83af9eed-64fc-493f-bde4-17354f7b7887" provisioned
I0402 22:37:20.625538       1 controller.go:1455] provision "default/test-pvc" class "general": succeeded
I0402 22:37:20.625547       1 volume_store.go:154] Saving volume pvc-83af9eed-64fc-493f-bde4-17354f7b7887
I0402 22:37:20.638281       1 volume_store.go:157] Volume pvc-83af9eed-64fc-493f-bde4-17354f7b7887 saved
I0402 22:37:20.638355       1 controller.go:1069] Claim processing succeeded, removing PVC 83af9eed-64fc-493f-bde4-17354f7b7887 from claims in progress
I0402 22:37:20.638602       1 event.go:285] Event(v1.ObjectReference{Kind:"PersistentVolumeClaim", Namespace:"default", Name:"test-pvc", UID:"83af9eed-64fc-493f-bde4-17354f7b7887", APIVersion:"v1", ResourceVersion:"2358", FieldPath:""}): type: 'Normal' reason: 'ProvisioningSucceeded' Successfully provisioned volume pvc-83af9eed-64fc-493f-bde4-17354f7b7887
I0402 22:37:24.357234       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:37:29.367988       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:37:34.377194       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:37:39.387173       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:37:44.395401       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:37:49.405809       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:37:54.416315       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:37:59.425809       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0402 22:38:04.436021       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com