Provisioner logging PVC related info which are created by other drivers

[Madhu-1]

am not sure this is a bug, want to know is this expected?

currently, we have 2 CSI drivers running if I create a PVC with one driver am seeing logs of the created PVC in another external-provisioner container. is this expected?

logs from external-provisoner container of driver 2:

I0807 09:00:58.894974       1 controller.go:769] Starting provisioner controller cephfs.csi.ceph.com_csi-cephfsplugin-provisioner-0_df90bdec-b8f1-11e9-aca2-96fe1c690ace!
I0807 09:00:58.895125       1 volume_store.go:90] Starting save volume queue
I0807 09:00:58.895478       1 reflector.go:123] Starting reflector *v1.PersistentVolumeClaim (15m0s) from sigs.k8s.io/sig-storage-lib-external-provisioner/controller/controller.go:800
I0807 09:00:58.895505       1 reflector.go:161] Listing and watching *v1.PersistentVolumeClaim from sigs.k8s.io/sig-storage-lib-external-provisioner/controller/controller.go:800
I0807 09:00:58.895646       1 reflector.go:123] Starting reflector *v1.PersistentVolume (15m0s) from sigs.k8s.io/sig-storage-lib-external-provisioner/controller/controller.go:803
I0807 09:00:58.895665       1 reflector.go:161] Listing and watching *v1.PersistentVolume from sigs.k8s.io/sig-storage-lib-external-provisioner/controller/controller.go:803
I0807 09:00:58.896013       1 reflector.go:123] Starting reflector *v1.StorageClass (15m0s) from sigs.k8s.io/sig-storage-lib-external-provisioner/controller/controller.go:806
I0807 09:00:58.896042       1 reflector.go:161] Listing and watching *v1.StorageClass from sigs.k8s.io/sig-storage-lib-external-provisioner/controller/controller.go:806
I0807 09:00:58.996819       1 shared_informer.go:123] caches populated
I0807 09:00:58.997780       1 controller.go:818] Started provisioner controller cephfs.csi.ceph.com_csi-cephfsplugin-provisioner-0_df90bdec-b8f1-11e9-aca2-96fe1c690ace!
I0807 09:01:20.848947       1 controller.go:979] Final error received, removing PVC 48ad3135-eea5-44fb-9586-48e598357100 from claims in progress
I0807 09:01:20.851830       1 controller.go:902] Provisioning succeeded, removing PVC 48ad3135-eea5-44fb-9586-48e598357100 from claims in progress
I0807 09:01:20.865778       1 controller.go:979] Final error received, removing PVC 48ad3135-eea5-44fb-9586-48e598357100 from claims in progress
I0807 09:01:20.865805       1 controller.go:902] Provisioning succeeded, removing PVC 48ad3135-eea5-44fb-9586-48e598357100 from claims in progress
I0807 09:01:25.410868       1 controller.go:979] Final error received, removing PVC 48ad3135-eea5-44fb-9586-48e598357100 from claims in progress
I0807 09:01:25.410901       1 controller.go:902] Provisioning succeeded, removing PVC 48ad3135-eea5-44fb-9586-48e598357100 from claims in progress
I0807 09:01:25.419597       1 controller.go:979] Final error received, removing PVC 48ad3135-eea5-44fb-9586-48e598357100 from claims in progress
I0807 09:01:25.419634       1 controller.go:902] Provisioning succeeded, removing PVC 48ad3135-eea5-44fb-9586-48e598357100 from claims in progress

note PVC 48ad3135-eea5-44fb-9586-48e598357100 is created/processed by another driver.

logs from the external-provisoner container of driver 1:

f1-11e9-b87a-0a2a43d1de18 VolumeContext:map[clusterID:abcd imageFeatures:layering imageFormat:2 pool:replicapool] ContentSource:<nil> AccessibleTopology:[] XXX_NoUnkeyedLiteral:{} XXX_unrecognized:[] XXX_sizecache:0}
I0807 09:01:25.348848       1 controller.go:596] 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-0004-abcd-0000000000000001-ecc0f84b-b8f1-11e9-b87a-0a2a43d1de18,ReadOnly:false,FSType:ext4,VolumeAttributes:map[string]string{clusterID: abcd,imageFeatures: layering,imageFormat: 2,pool: replicapool,storage.kubernetes.io/csiProvisionerIdentity: 1565168451486-8081-rbd.csi.ceph.com,},ControllerPublishSecretRef:nil,NodeStageSecretRef:&SecretReference{Name:csi-rbd-secret,Namespace:default,},NodePublishSecretRef:nil,ControllerExpandSecretRef:nil,}}
I0807 09:01:25.349037       1 controller.go:1278] provision "default/rbd-pvc" class "csi-rbd-sc": volume "pvc-48ad3135-eea5-44fb-9586-48e598357100" provisioned
I0807 09:01:25.349073       1 controller.go:1295] provision "default/rbd-pvc" class "csi-rbd-sc": succeeded
I0807 09:01:25.349085       1 volume_store.go:147] Saving volume pvc-48ad3135-eea5-44fb-9586-48e598357100
I0807 09:01:25.369960       1 volume_store.go:150] Volume pvc-48ad3135-eea5-44fb-9586-48e598357100 saved
I0807 09:01:25.370040       1 controller.go:979] Final error received, removing PVC 48ad3135-eea5-44fb-9586-48e598357100 from claims in progress
I0807 09:01:25.370057       1 controller.go:902] Provisioning succeeded, removing PVC 48ad3135-eea5-44fb-9586-48e598357100 from claims in progress
I0807 09:01:25.400270       1 controller.go:979] Final error received, removing PVC 48ad3135-eea5-44fb-9586-48e598357100 from claims in progress
I0807 09:01:25.400300       1 controller.go:902] Provisioning succeeded, removing PVC 48ad3135-eea5-44fb-9586-48e598357100 from claims in progress
I0807 09:01:25.410875       1 controller.go:979] Final error received, removing PVC 48ad3135-eea5-44fb-9586-48e598357100 from claims in progress
I0807 09:01:25.410910       1 controller.go:902] Provisioning succeeded, removing PVC 48ad3135-eea5-44fb-9586-48e598357100 from claims in progress
I0807 09:08:02.511614       1 reflector.go:370] sigs.k8s.io/sig-storage-lib-external-provisioner/controller/controller.go:803: Watch close - *v1.PersistentVolume total 2 items received
I0807 09:09:10.513976       1 reflector.go:370] sigs.k8s.io/sig-storage-lib-external-provisioner/controller/controller.go:806: Watch close - *v1.StorageClass total 1 items received

[Madhu-1]

CC @jsafrane @msau42

[msau42]

@jsafrane does kubernetes-sigs/sig-storage-lib-external-provisioner#53 address this?

[Madhu-1]

@msau42 looks like kubernetes-sigs/sig-storage-lib-external-provisioner#53 fixes this and kubernetes-sigs/sig-storage-lib-external-provisioner#53 is merged to master, we are using v.4.0.0 sig-storage-lib-external-provisioner in external-provisioner

[msau42]

/kind bug

[msau42]

@jsafrane can you help update the lib-provisioner dependency to get the fix?
/assign @jsafrane