Skip to Content.
Sympa Menu

grouper-users - [grouper-users] [PSPNG] Cannot start FullSync of .... Incremental provisioning underway since...

Subject: Grouper Users - Open Discussion List

List archive

[grouper-users] [PSPNG] Cannot start FullSync of .... Incremental provisioning underway since...


Chronological Thread 
  • From: Yoann Delattre <>
  • To: "" <>
  • Subject: [grouper-users] [PSPNG] Cannot start FullSync of .... Incremental provisioning underway since...
  • Date: Wed, 27 Feb 2019 15:17:34 +0100
  • Arc-authentication-results: i=1; webmail.ac-lille.fr; auth=pass
  • Arc-message-signature: i=1; a=rsa-sha256; c=relaxed/relaxed; d=ac-lille.fr; s=aclille-dkim; t=1551277056; h=from:from:sender:reply-to:subject:subject:date:date: message-id:message-id:to:to:cc:mime-version:mime-version: content-type:content-type:content-transfer-encoding:in-reply-to: references; bh=ZqXvjrCCIilP7kL8p7cSRVhqP7wzpzdQcj7tkS/570A=; b=Ho3gPgtJsbvcbU/Ep++CNuUNbv9VE2DYiw5UNWq64m+n837gVrUJypJZHuL3EYZQ+2yjCq 4OP7VdK+lXOwpr6TeW36AkcS++dlHCjel6Nu21adPFw2nNp+QIxFYgCkYkTj2j8+9PCkzO p8tboHxEDoMU32z8FumD5sDbq2aUkts=
  • Arc-seal: i=1; s=aclille-dkim; d=ac-lille.fr; t=1551277056; a=rsa-sha256; cv=none; b=GlvzzcExdFdVPLiQp4yvGyUySPYKqbeH1wktv0jTAUIDKojaokHL6mbaRkI/bar6MtVSRb mHmFL2u46h835FnLOjvkXwTaF39fUSoksCKw0zG4dPiChfEhu4I7DILuk1h3rmvJdNp+Ek MHldpfYcy2EMuoagLKq8YoQEpAK/b2I=

Hello,

in the log, i get messages like this :

WARN  ProvisionerCoordinator$ProvisioningStatus.lockForFullSyncWhenNoIncrementalIsUnderway(73) -  - pspng_brancheGrouper: Cannot start FullSync of services:12944:tous. Incremental provisioning underway since Wed Feb 27 11:33:36 CET 2019. We'll give up and move ahead anyway in 109 seconds.

that's correct, some groups are locked (around 500):

2019-02-27 11:33:36,685: [DefaultQuartzScheduler_Worker-1] INFO  ProvisionerCoordinator$ProvisioningStatus.lockForIncrementalProvisioningWhenNoFullSyncIsUnderway(139) -  - pspng_brancheGrouper: Locking group before incremental sync: services:12943:tous
2019-02-27 11:33:36,685: [DefaultQuartzScheduler_Worker-1] INFO  ProvisionerCoordinator$ProvisioningStatus.lockForIncrementalProvisioningWhenNoFullSyncIsUnderway(139) -  - pspng_brancheGrouper: Locking group before incremental sync: services:12944:direction
2019-02-27 11:33:36,685: [DefaultQuartzScheduler_Worker-1] INFO  ProvisionerCoordinator$ProvisioningStatus.lockForIncrementalProvisioningWhenNoFullSyncIsUnderway(139) -  - pspng_brancheGrouper: Locking group before incremental sync: services:12944:secretaires
2019-02-27 11:33:36,685: [DefaultQuartzScheduler_Worker-1] INFO  ProvisionerCoordinator$ProvisioningStatus.lockForIncrementalProvisioningWhenNoFullSyncIsUnderway(139) -  - pspng_brancheGrouper: Locking group before incremental sync: services:12944:tous
2019-02-27 11:33:36,685: [DefaultQuartzScheduler_Worker-1] INFO  ProvisionerCoordinator$ProvisioningStatus.lockForIncrementalProvisioningWhenNoFullSyncIsUnderway(139) -  - pspng_brancheGrouper: Locking group before incremental sync: services:12945:direction
2019-02-27 11:33:36,685: [DefaultQuartzScheduler_Worker-1] INFO  ProvisionerCoordinator$ProvisioningStatus.lockForIncrementalProvisioningWhenNoFullSyncIsUnderway(139) -  - pspng_brancheGrouper: Locking group before incremental sync: services:12945:secretaires
2019-02-27 11:33:36,685: [DefaultQuartzScheduler_Worker-1] INFO  ProvisionerCoordinator$ProvisioningStatus.lockForIncrementalProvisioningWhenNoFullSyncIsUnderway(139) -  - pspng_brancheGrouper: Locking group before incremental sync: services:12945:tous
2019-02-27 11:33:36,685: [DefaultQuartzScheduler_Worker-1] INFO  ProvisionerCoordinator$ProvisioningStatus.lockForIncrementalProvisioningWhenNoFullSyncIsUnderway(139) -  - pspng_brancheGrouper: Locking group before incremental sync: services:12946:direction
...

And then shortly after, PSPNG started a full-sync :

2019-02-27 11:34:42,611: [DefaultQuartzScheduler_Worker-1] INFO  Provisioner.workItemShouldBeHandledByFullSyncOfEverything(1767) -  - pspng_brancheGrouper: Change might change group selection: ProvisioningWorkItem[done=false,clog=clog #90287666 / ChangeLog type: stem: updateStem]
2019-02-27 11:34:42,611: [DefaultQuartzScheduler_Worker-1] INFO  Provisioner.provisionItem(1093) -  - pspng_brancheGrouper: Performing sync of all groups to process work item: ProvisioningWorkItem[done=false,clog=clog #90287666 / ChangeLog type: stem: updateStem]
2019-02-27 11:34:42,612: [DefaultQuartzScheduler_Worker-1] INFO  FullSyncProvisioner.queueAllGroupsForFullSync(551) -  - FullSyncer(pspng_brancheGrouper): Queuing all groups for full sync. (Work item invokes full sync: ProvisioningWorkItem[done=false,clog=clog #90287666 / ChangeLog type: stem: updateStem])
Also the log say "We'll give up and move ahead anyway in 300 seconds." but when he reach 300 seconds, he start over. And only after 600 seconds (300 x 2), PSPNG start the full-sync.
2019-02-27 13:24:11,518: [FullSyncer(pspng_brancheGrouper)-Thread] WARN  ProvisionerCoordinator$ProvisioningStatus.lockForFullSyncWhenNoIncrementalIsUnderway(73) -  - pspng_brancheGrouper: Cannot start FullSync of services:12944:tous. Incremental provisioning underway since Wed Feb 27 11:33:36 CET 2019. We'll give up and move ahead anyway in 9 seconds.
2019-02-27 13:24:21,521: [FullSyncer(pspng_brancheGrouper)-Thread] WARN  ProvisionerCoordinator$ProvisioningStatus.lockForFullSyncWhenNoIncrementalIsUnderway(73) -  - pspng_brancheGrouper: Cannot start FullSync of services:12944:tous. Incremental provisioning underway since Wed Feb 27 11:33:36 CET 2019. We'll give up and move ahead anyway in 300 seconds.

Is that a normal behavior ?

Thanks for your help,

Yoann.

PSP : I use PSPNG 2.3 Patch 21

--
Yoann Delattre ✆ 03 20 95 69 10 Équipe SIAD (Systèmes d'Information et Aide à la Décision)
DSI de l'académie de Lille (Direction des Systèmes d'Information)
110 avenue Gaston Berger - 59000 Lille



Archive powered by MHonArc 2.6.19.

Top of Page