I0225 02:14:49.640734       1 feature_gate.go:245] feature gates: &{map[]}
I0225 02:14:49.640792       1 csi-provisioner.go:139] Version: v3.1.0
I0225 02:14:49.640796       1 csi-provisioner.go:162] Building kube configs for running in cluster...
I0225 02:14:49.641212       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
W0225 02:14:59.641337       1 connection.go:173] Still connecting to unix:///csi/csi-provisioner.sock
W0225 02:15:09.641970       1 connection.go:173] Still connecting to unix:///csi/csi-provisioner.sock
W0225 02:15:19.641744       1 connection.go:173] Still connecting to unix:///csi/csi-provisioner.sock
I0225 02:15:20.318089       1 common.go:111] Probing CSI driver for readiness
I0225 02:15:20.318110       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0225 02:15:20.318115       1 connection.go:184] GRPC request: {}
I0225 02:15:20.324546       1 connection.go:186] GRPC response: {}
I0225 02:15:20.324715       1 connection.go:187] GRPC error: <nil>
I0225 02:15:20.324811       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo
I0225 02:15:20.324916       1 connection.go:184] GRPC request: {}
I0225 02:15:20.326054       1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0225 02:15:20.326124       1 connection.go:187] GRPC error: <nil>
I0225 02:15:20.326136       1 csi-provisioner.go:206] Detected CSI driver rbd.csi.ceph.com
I0225 02:15:20.326163       1 csi-provisioner.go:216] Supports migration from in-tree plugin: kubernetes.io/rbd
I0225 02:15:20.326262       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0225 02:15:20.326886       1 common.go:111] Probing CSI driver for readiness
I0225 02:15:20.326957       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0225 02:15:20.326991       1 connection.go:184] GRPC request: {}
I0225 02:15:20.327736       1 connection.go:186] GRPC response: {}
I0225 02:15:20.327768       1 connection.go:187] GRPC error: <nil>
I0225 02:15:20.327815       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0225 02:15:20.327819       1 connection.go:184] GRPC request: {}
I0225 02:15:20.329696       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0225 02:15:20.329858       1 connection.go:187] GRPC error: <nil>
I0225 02:15:20.329866       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0225 02:15:20.329870       1 connection.go:184] GRPC request: {}
I0225 02:15:20.333640       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0225 02:15:20.334016       1 connection.go:187] GRPC error: <nil>
I0225 02:15:20.334230       1 csi-provisioner.go:278] CSI driver does not support PUBLISH_UNPUBLISH_VOLUME, not watching VolumeAttachments
I0225 02:15:20.336875       1 controller.go:732] Using saving PVs to API server in background
I0225 02:15:20.337384       1 leaderelection.go:248] attempting to acquire leader lease kube-system/rbd-csi-ceph-com...
I0225 02:15:20.349165       1 leaderelection.go:258] successfully acquired lease kube-system/rbd-csi-ceph-com
I0225 02:15:20.349301       1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-5d8ff44fc9-zs78f
I0225 02:15:20.349543       1 leader_election.go:205] became leader, starting
I0225 02:15:20.349675       1 reflector.go:219] Starting reflector *v1.StorageClass (1h0m0s) from k8s.io/client-go/informers/factory.go:134
I0225 02:15:20.349853       1 reflector.go:255] Listing and watching *v1.StorageClass from k8s.io/client-go/informers/factory.go:134
I0225 02:15:20.349812       1 reflector.go:219] Starting reflector *v1.PersistentVolumeClaim (15m0s) from k8s.io/client-go/informers/factory.go:134
I0225 02:15:20.350094       1 reflector.go:255] Listing and watching *v1.PersistentVolumeClaim from k8s.io/client-go/informers/factory.go:134
I0225 02:15:20.353348       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:15:20.450393       1 shared_informer.go:270] caches populated
I0225 02:15:20.450428       1 shared_informer.go:270] caches populated
I0225 02:15:20.450445       1 controller.go:811] Starting provisioner controller rbd.csi.ceph.com_ceph-csi-rbd-provisioner-5d8ff44fc9-zs78f_430f804a-16ac-4dde-90bb-b061ce1aee5b!
I0225 02:15:20.450483       1 clone_controller.go:66] Starting CloningProtection controller
I0225 02:15:20.450509       1 clone_controller.go:82] Started CloningProtection controller
I0225 02:15:20.450526       1 volume_store.go:97] Starting save volume queue
I0225 02:15:20.450648       1 reflector.go:219] Starting reflector *v1.PersistentVolume (15m0s) from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:845
I0225 02:15:20.450661       1 reflector.go:255] Listing and watching *v1.PersistentVolume from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:845
I0225 02:15:20.450961       1 reflector.go:219] Starting reflector *v1.StorageClass (15m0s) from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:848
I0225 02:15:20.450972       1 reflector.go:255] Listing and watching *v1.StorageClass from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:848
I0225 02:15:20.551362       1 shared_informer.go:270] caches populated
I0225 02:15:20.552407       1 controller.go:860] Started provisioner controller rbd.csi.ceph.com_ceph-csi-rbd-provisioner-5d8ff44fc9-zs78f_430f804a-16ac-4dde-90bb-b061ce1aee5b!
I0225 02:15:25.362392       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:15:30.374329       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:15:35.384142       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:15:40.391994       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:15:45.402758       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:15:50.410660       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:15:55.418193       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:16:00.428019       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:16:05.435883       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:16:10.443840       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:16:15.451099       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:16:20.459519       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:16:25.467479       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:16:30.476522       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:16:35.487024       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:16:40.496161       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:16:45.504982       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:16:50.518485       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:16:55.531189       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:17:00.539821       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:17:05.548975       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:17:10.560851       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:17:15.570321       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:17:20.582624       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:17:25.592502       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:17:30.603097       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:17:35.613837       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:17:40.626989       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:17:45.637089       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:17:50.650832       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:17:55.661639       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:18:00.675912       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:18:05.686530       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:18:10.698667       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:18:15.708443       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:18:20.718618       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:18:25.728357       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:18:25.840799       1 controller.go:1337] provision "default/test-pvc" class "general": started
I0225 02:18:25.840867       1 controller.go:528] skip translation of storage class for plugin: rbd.csi.ceph.com
I0225 02:18:25.841584       1 event.go:285] Event(v1.ObjectReference{Kind:"PersistentVolumeClaim", Namespace:"default", Name:"test-pvc", UID:"7e2f2520-508d-4770-a04a-5398ecf2dfec", APIVersion:"v1", ResourceVersion:"1906", FieldPath:""}): type: 'Normal' reason: 'Provisioning' External provisioner is provisioning volume for claim "default/test-pvc"
I0225 02:18:25.843586       1 connection.go:183] GRPC call: /csi.v1.Controller/CreateVolume
I0225 02:18:25.843601       1 connection.go:184] GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-7e2f2520-508d-4770-a04a-5398ecf2dfec","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-7e2f2520-508d-4770-a04a-5398ecf2dfec","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}}]}
I0225 02:18:27.414590       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-7e2f2520-508d-4770-a04a-5398ecf2dfec","csi.storage.k8s.io/pvc/name":"test-pvc","csi.storage.k8s.io/pvc/namespace":"default","imageFeatures":"layering","imageName":"csi-vol-442ad9be-11f0-11f1-bfa5-e6d291f31028","journalPool":"kube","pool":"kube","thickProvision":"false"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-442ad9be-11f0-11f1-bfa5-e6d291f31028"}}
I0225 02:18:27.414868       1 connection.go:187] GRPC error: <nil>
I0225 02:18:27.414879       1 controller.go:774] create volume rep: {CapacityBytes:5368709120 VolumeId:0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-442ad9be-11f0-11f1-bfa5-e6d291f31028 VolumeContext:map[clusterID:4837cbf8-4f90-4300-b3f6-726c9b9f89b4 csi.storage.k8s.io/pv/name:pvc-7e2f2520-508d-4770-a04a-5398ecf2dfec csi.storage.k8s.io/pvc/name:test-pvc csi.storage.k8s.io/pvc/namespace:default imageFeatures:layering imageName:csi-vol-442ad9be-11f0-11f1-bfa5-e6d291f31028 journalPool:kube pool:kube thickProvision:false] ContentSource:<nil> AccessibleTopology:[] XXX_NoUnkeyedLiteral:{} XXX_unrecognized:[] XXX_sizecache:0}
I0225 02:18:27.414923       1 controller.go:858] successfully created PV pvc-7e2f2520-508d-4770-a04a-5398ecf2dfec for PVC test-pvc and csi volume name 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-442ad9be-11f0-11f1-bfa5-e6d291f31028
I0225 02:18:27.414937       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-442ad9be-11f0-11f1-bfa5-e6d291f31028,ReadOnly:false,FSType:ext4,VolumeAttributes:map[string]string{clusterID: 4837cbf8-4f90-4300-b3f6-726c9b9f89b4,csi.storage.k8s.io/pv/name: pvc-7e2f2520-508d-4770-a04a-5398ecf2dfec,csi.storage.k8s.io/pvc/name: test-pvc,csi.storage.k8s.io/pvc/namespace: default,imageFeatures: layering,imageName: csi-vol-442ad9be-11f0-11f1-bfa5-e6d291f31028,journalPool: kube,pool: kube,storage.kubernetes.io/csiProvisionerIdentity: 1771985720334-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,},}}
I0225 02:18:27.415163       1 controller.go:1442] provision "default/test-pvc" class "general": volume "pvc-7e2f2520-508d-4770-a04a-5398ecf2dfec" provisioned
I0225 02:18:27.415172       1 controller.go:1455] provision "default/test-pvc" class "general": succeeded
I0225 02:18:27.415178       1 volume_store.go:154] Saving volume pvc-7e2f2520-508d-4770-a04a-5398ecf2dfec
I0225 02:18:27.422933       1 volume_store.go:157] Volume pvc-7e2f2520-508d-4770-a04a-5398ecf2dfec saved
I0225 02:18:27.422999       1 controller.go:1069] Claim processing succeeded, removing PVC 7e2f2520-508d-4770-a04a-5398ecf2dfec from claims in progress
I0225 02:18:27.423248       1 event.go:285] Event(v1.ObjectReference{Kind:"PersistentVolumeClaim", Namespace:"default", Name:"test-pvc", UID:"7e2f2520-508d-4770-a04a-5398ecf2dfec", APIVersion:"v1", ResourceVersion:"1906", FieldPath:""}): type: 'Normal' reason: 'ProvisioningSucceeded' Successfully provisioned volume pvc-7e2f2520-508d-4770-a04a-5398ecf2dfec
I0225 02:18:30.736968       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:18:35.744766       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:18:40.757191       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:18:45.768191       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:18:50.776848       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:18:55.788583       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:19:00.799280       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:19:05.808468       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:19:10.835306       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0225 02:19:15.844288       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com