I0211 03:20:49.769260       1 feature_gate.go:245] feature gates: &{map[]}
I0211 03:20:49.769462       1 csi-provisioner.go:139] Version: v3.1.0
I0211 03:20:49.769511       1 csi-provisioner.go:162] Building kube configs for running in cluster...
I0211 03:20:49.769939       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
W0211 03:20:59.770340       1 connection.go:173] Still connecting to unix:///csi/csi-provisioner.sock
W0211 03:21:09.770889       1 connection.go:173] Still connecting to unix:///csi/csi-provisioner.sock
I0211 03:21:15.200244       1 common.go:111] Probing CSI driver for readiness
I0211 03:21:15.200261       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0211 03:21:15.200265       1 connection.go:184] GRPC request: {}
I0211 03:21:15.230456       1 connection.go:186] GRPC response: {}
I0211 03:21:15.230507       1 connection.go:187] GRPC error: <nil>
I0211 03:21:15.230517       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginInfo
I0211 03:21:15.230519       1 connection.go:184] GRPC request: {}
I0211 03:21:15.232286       1 connection.go:186] GRPC response: {"name":"rbd.csi.ceph.com","vendor_version":"v3.5.1"}
I0211 03:21:15.232375       1 connection.go:187] GRPC error: <nil>
I0211 03:21:15.232424       1 csi-provisioner.go:206] Detected CSI driver rbd.csi.ceph.com
I0211 03:21:15.232461       1 csi-provisioner.go:216] Supports migration from in-tree plugin: kubernetes.io/rbd
I0211 03:21:15.232561       1 connection.go:154] Connecting to unix:///csi/csi-provisioner.sock
I0211 03:21:15.234241       1 common.go:111] Probing CSI driver for readiness
I0211 03:21:15.234311       1 connection.go:183] GRPC call: /csi.v1.Identity/Probe
I0211 03:21:15.234366       1 connection.go:184] GRPC request: {}
I0211 03:21:15.234967       1 connection.go:186] GRPC response: {}
I0211 03:21:15.235062       1 connection.go:187] GRPC error: <nil>
I0211 03:21:15.235483       1 connection.go:183] GRPC call: /csi.v1.Identity/GetPluginCapabilities
I0211 03:21:15.235556       1 connection.go:184] GRPC request: {}
I0211 03:21:15.236725       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Service":{"type":1}}},{"Type":{"VolumeExpansion":{"type":1}}},{"Type":{"Service":{"type":2}}}]}
I0211 03:21:15.236918       1 connection.go:187] GRPC error: <nil>
I0211 03:21:15.236967       1 connection.go:183] GRPC call: /csi.v1.Controller/ControllerGetCapabilities
I0211 03:21:15.237008       1 connection.go:184] GRPC request: {}
I0211 03:21:15.239703       1 connection.go:186] GRPC response: {"capabilities":[{"Type":{"Rpc":{"type":1}}},{"Type":{"Rpc":{"type":5}}},{"Type":{"Rpc":{"type":7}}},{"Type":{"Rpc":{"type":9}}}]}
I0211 03:21:15.239839       1 connection.go:187] GRPC error: <nil>
I0211 03:21:15.239926       1 csi-provisioner.go:278] CSI driver does not support PUBLISH_UNPUBLISH_VOLUME, not watching VolumeAttachments
I0211 03:21:15.240531       1 controller.go:732] Using saving PVs to API server in background
I0211 03:21:15.241022       1 leaderelection.go:248] attempting to acquire leader lease kube-system/rbd-csi-ceph-com...
I0211 03:21:15.261615       1 leaderelection.go:258] successfully acquired lease kube-system/rbd-csi-ceph-com
I0211 03:21:15.261793       1 leader_election.go:212] new leader detected, current leader: ceph-csi-rbd-provisioner-5d8ff44fc9-v7t86
I0211 03:21:15.264118       1 leader_election.go:205] became leader, starting
I0211 03:21:15.270061       1 reflector.go:219] Starting reflector *v1.StorageClass (1h0m0s) from k8s.io/client-go/informers/factory.go:134
I0211 03:21:15.270221       1 reflector.go:255] Listing and watching *v1.StorageClass from k8s.io/client-go/informers/factory.go:134
I0211 03:21:15.270521       1 reflector.go:219] Starting reflector *v1.PersistentVolumeClaim (15m0s) from k8s.io/client-go/informers/factory.go:134
I0211 03:21:15.270538       1 reflector.go:255] Listing and watching *v1.PersistentVolumeClaim from k8s.io/client-go/informers/factory.go:134
I0211 03:21:15.284889       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:21:15.364860       1 shared_informer.go:270] caches populated
I0211 03:21:15.365891       1 shared_informer.go:270] caches populated
I0211 03:21:15.365943       1 controller.go:811] Starting provisioner controller rbd.csi.ceph.com_ceph-csi-rbd-provisioner-5d8ff44fc9-v7t86_d31613e4-2a2f-46a7-81b6-1665c9cdcb09!
I0211 03:21:15.366173       1 clone_controller.go:66] Starting CloningProtection controller
I0211 03:21:15.366331       1 clone_controller.go:82] Started CloningProtection controller
I0211 03:21:15.366190       1 volume_store.go:97] Starting save volume queue
I0211 03:21:15.366576       1 reflector.go:219] Starting reflector *v1.PersistentVolume (15m0s) from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:845
I0211 03:21:15.366624       1 reflector.go:255] Listing and watching *v1.PersistentVolume from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:845
I0211 03:21:15.367823       1 reflector.go:219] Starting reflector *v1.StorageClass (15m0s) from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:848
I0211 03:21:15.367884       1 reflector.go:255] Listing and watching *v1.StorageClass from sigs.k8s.io/sig-storage-lib-external-provisioner/v8/controller/controller.go:848
I0211 03:21:15.468180       1 shared_informer.go:270] caches populated
I0211 03:21:15.468639       1 controller.go:860] Started provisioner controller rbd.csi.ceph.com_ceph-csi-rbd-provisioner-5d8ff44fc9-v7t86_d31613e4-2a2f-46a7-81b6-1665c9cdcb09!
I0211 03:21:20.293654       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:21:25.301482       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:21:30.311776       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:21:35.319591       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:21:40.326677       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:21:45.340620       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:21:50.346221       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:21:55.354329       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:22:00.365837       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:22:05.375017       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:22:10.380843       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:22:15.388117       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:22:20.397733       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:22:25.408690       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:22:30.418236       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:22:35.425928       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:22:40.431238       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:22:45.437805       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:22:50.447199       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:22:55.453522       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:23:00.460509       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:23:05.468977       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:23:10.478083       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:23:15.484571       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:23:20.490799       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:23:25.500420       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:23:30.510659       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:23:35.518039       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:23:40.523982       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:23:45.533176       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:23:50.543963       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:23:55.552817       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:24:00.563440       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:24:05.573365       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:24:10.582458       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:24:11.309879       1 controller.go:1337] provision "default/test-pvc" class "general": started
I0211 03:24:11.310630       1 controller.go:528] skip translation of storage class for plugin: rbd.csi.ceph.com
I0211 03:24:11.312546       1 event.go:285] Event(v1.ObjectReference{Kind:"PersistentVolumeClaim", Namespace:"default", Name:"test-pvc", UID:"c6dd8ac5-de69-4fda-8de8-ff09a0421753", APIVersion:"v1", ResourceVersion:"1776", FieldPath:""}): type: 'Normal' reason: 'Provisioning' External provisioner is provisioning volume for claim "default/test-pvc"
I0211 03:24:11.317259       1 connection.go:183] GRPC call: /csi.v1.Controller/CreateVolume
I0211 03:24:11.319540       1 connection.go:184] GRPC request: {"capacity_range":{"required_bytes":5368709120},"name":"pvc-c6dd8ac5-de69-4fda-8de8-ff09a0421753","parameters":{"clusterID":"4837cbf8-4f90-4300-b3f6-726c9b9f89b4","csi.storage.k8s.io/pv/name":"pvc-c6dd8ac5-de69-4fda-8de8-ff09a0421753","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 03:24:12.583127       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-c6dd8ac5-de69-4fda-8de8-ff09a0421753","csi.storage.k8s.io/pvc/name":"test-pvc","csi.storage.k8s.io/pvc/namespace":"default","imageFeatures":"layering","imageName":"csi-vol-22131d2f-06f9-11f1-8a46-7e193eecca25","journalPool":"kube","pool":"kube","thickProvision":"false"},"volume_id":"0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-22131d2f-06f9-11f1-8a46-7e193eecca25"}}
I0211 03:24:12.583249       1 connection.go:187] GRPC error: <nil>
I0211 03:24:12.583261       1 controller.go:774] create volume rep: {CapacityBytes:5368709120 VolumeId:0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-22131d2f-06f9-11f1-8a46-7e193eecca25 VolumeContext:map[clusterID:4837cbf8-4f90-4300-b3f6-726c9b9f89b4 csi.storage.k8s.io/pv/name:pvc-c6dd8ac5-de69-4fda-8de8-ff09a0421753 csi.storage.k8s.io/pvc/name:test-pvc csi.storage.k8s.io/pvc/namespace:default imageFeatures:layering imageName:csi-vol-22131d2f-06f9-11f1-8a46-7e193eecca25 journalPool:kube pool:kube thickProvision:false] ContentSource:<nil> AccessibleTopology:[] XXX_NoUnkeyedLiteral:{} XXX_unrecognized:[] XXX_sizecache:0}
I0211 03:24:12.584351       1 controller.go:858] successfully created PV pvc-c6dd8ac5-de69-4fda-8de8-ff09a0421753 for PVC test-pvc and csi volume name 0001-0024-4837cbf8-4f90-4300-b3f6-726c9b9f89b4-0000000000000002-22131d2f-06f9-11f1-8a46-7e193eecca25
I0211 03:24:12.584371       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-22131d2f-06f9-11f1-8a46-7e193eecca25,ReadOnly:false,FSType:ext4,VolumeAttributes:map[string]string{clusterID: 4837cbf8-4f90-4300-b3f6-726c9b9f89b4,csi.storage.k8s.io/pv/name: pvc-c6dd8ac5-de69-4fda-8de8-ff09a0421753,csi.storage.k8s.io/pvc/name: test-pvc,csi.storage.k8s.io/pvc/namespace: default,imageFeatures: layering,imageName: csi-vol-22131d2f-06f9-11f1-8a46-7e193eecca25,journalPool: kube,pool: kube,storage.kubernetes.io/csiProvisionerIdentity: 1770780075239-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 03:24:12.587796       1 controller.go:1442] provision "default/test-pvc" class "general": volume "pvc-c6dd8ac5-de69-4fda-8de8-ff09a0421753" provisioned
I0211 03:24:12.587807       1 controller.go:1455] provision "default/test-pvc" class "general": succeeded
I0211 03:24:12.587811       1 volume_store.go:154] Saving volume pvc-c6dd8ac5-de69-4fda-8de8-ff09a0421753
I0211 03:24:12.602342       1 volume_store.go:157] Volume pvc-c6dd8ac5-de69-4fda-8de8-ff09a0421753 saved
I0211 03:24:12.602687       1 event.go:285] Event(v1.ObjectReference{Kind:"PersistentVolumeClaim", Namespace:"default", Name:"test-pvc", UID:"c6dd8ac5-de69-4fda-8de8-ff09a0421753", APIVersion:"v1", ResourceVersion:"1776", FieldPath:""}): type: 'Normal' reason: 'ProvisioningSucceeded' Successfully provisioned volume pvc-c6dd8ac5-de69-4fda-8de8-ff09a0421753
I0211 03:24:12.602797       1 controller.go:1069] Claim processing succeeded, removing PVC c6dd8ac5-de69-4fda-8de8-ff09a0421753 from claims in progress
I0211 03:24:15.592722       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:24:20.606289       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:24:25.612627       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:24:30.621873       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:24:35.632174       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:24:40.638018       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:24:45.648888       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:24:50.660721       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:24:55.680488       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:25:00.686376       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:25:05.698507       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com
I0211 03:25:10.706655       1 leaderelection.go:278] successfully renewed lease kube-system/rbd-csi-ceph-com