[jira] [Comment Edited] (JCRVLT-730) Unstable IT: VaultSyncServiceImplIT.testAddRemoveFileFromNonVltCheckoutFolder

2023-11-14 Thread Konrad Windszus (Jira)


[ 
https://issues.apache.org/jira/browse/JCRVLT-730?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel=17785824#comment-17785824
 ] 

Konrad Windszus edited comment on JCRVLT-730 at 11/14/23 3:14 PM:
--

Debug log exposes the following when the test is failing:

{code}
14:51:02.430 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
Starting sync cycle for 
SyncSpec{fileRoot=/tmp/junit13097876300643150377/junit11032106733571259963}.
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
Scanning filesystem for changes 
SyncSpec{fileRoot=/tmp/junit13097876300643150377/junit11032106733571259963}
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
onDirectoryCreate(/tmp/junit13097876300643150377/junit11032106733571259963/testroot),
 accepted=true
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
onFileCreate(/tmp/junit13097876300643150377/junit11032106733571259963/testroot/testfile.txt),
 accepted=true
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
onFileChange(/tmp/junit13097876300643150377/junit11032106733571259963/.vlt-sync-filter.xml),
 accepted=false
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler -  
about sync 
file://tmp/junit13097876300643150377/junit11032106733571259963/testroot -> 
jcr:///testroot
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler -  
rejected. filter does not include /testroot
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler -  
about sync 
file://tmp/junit13097876300643150377/junit11032106733571259963/testroot/testfile.txt
 -> jcr:///testroot/testfile.txt
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler -  
rejected. filter does not include /testroot/testfile.txt
14:51:02.447 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - Sync 
cycle completed for 
SyncSpec{fileRoot=/tmp/junit13097876300643150377/junit11032106733571259963}
{code}

So the filter.xml change is detected too late.


was (Author: kwin):
Debug log exposes the following when the test is failing:

{code}
14:51:02.430 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
Starting sync cycle for 
SyncSpec{fileRoot=/tmp/junit13097876300643150377/junit11032106733571259963}.
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
Scanning filesystem for changes 
SyncSpec{fileRoot=/tmp/junit13097876300643150377/junit11032106733571259963}
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
onDirectoryCreate(/tmp/junit13097876300643150377/junit11032106733571259963/testroot),
 accepted=true
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
onFileCreate(/tmp/junit13097876300643150377/junit11032106733571259963/testroot/testfile.txt),
 accepted=true
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
onFileChange(/tmp/junit13097876300643150377/junit11032106733571259963/.vlt-sync-filter.xml),
 accepted=false
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler -  
about sync 
file://tmp/junit13097876300643150377/junit11032106733571259963/testroot -> 
jcr:///testroot
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler -  
rejected. filter does not include /testroot
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler -  
about sync 
file://tmp/junit13097876300643150377/junit11032106733571259963/testroot/testfile.txt
 -> jcr:///testroot/testfile.txt
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler -  
rejected. filter does not include /testroot/testfile.txt
14:51:02.447 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - Sync 
cycle completed for 
SyncSpec{fileRoot=/tmp/junit13097876300643150377/junit11032106733571259963}
{code}

> Unstable IT: VaultSyncServiceImplIT.testAddRemoveFileFromNonVltCheckoutFolder
> -
>
> Key: JCRVLT-730
> URL: https://issues.apache.org/jira/browse/JCRVLT-730
> Project: Jackrabbit FileVault
>  Issue Type: Bug
>Reporter: Konrad Windszus
>Assignee: Konrad Windszus
>Priority: Major
> Fix For: 3.7.4
>
>
> {code}
> 16:52:18.914 [Vault Sync Thread] INFO  o.a.j.vault.sync.impl.SyncHandler - 
> Sync Once requested: JCR2FS
> 16:52:18.924 [Vault Sync Thread] INFO  o.a.j.vault.sync.impl.SyncLog - A 
> file:///tmp/junit6534468439393939733/junit3629697623599693036/testroot/
> 16:52:18.926 [Vault Sync Thread] INFO  o.a.j.vault.sync.impl.SyncLog - A 
> file:///tmp/junit6534468439393939733/junit3629697623599693036/testroot/testfile
> 16:52:18.927 [Vault Sync Thread] INFO  o.a.j.vault.sync.impl.SyncResult - 
> SyncResult: 

[jira] [Comment Edited] (JCRVLT-730) Unstable IT: VaultSyncServiceImplIT.testAddRemoveFileFromNonVltCheckoutFolder

2023-11-14 Thread Konrad Windszus (Jira)


[ 
https://issues.apache.org/jira/browse/JCRVLT-730?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel=17785824#comment-17785824
 ] 

Konrad Windszus edited comment on JCRVLT-730 at 11/14/23 3:13 PM:
--

Debug log exposes the following when the test is failing:

{code}
14:51:02.430 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
Starting sync cycle for 
SyncSpec{fileRoot=/tmp/junit13097876300643150377/junit11032106733571259963}.
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
Scanning filesystem for changes 
SyncSpec{fileRoot=/tmp/junit13097876300643150377/junit11032106733571259963}
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
onDirectoryCreate(/tmp/junit13097876300643150377/junit11032106733571259963/testroot),
 accepted=true
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
onFileCreate(/tmp/junit13097876300643150377/junit11032106733571259963/testroot/testfile.txt),
 accepted=true
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
onFileChange(/tmp/junit13097876300643150377/junit11032106733571259963/.vlt-sync-filter.xml),
 accepted=false
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler -  
about sync 
file://tmp/junit13097876300643150377/junit11032106733571259963/testroot -> 
jcr:///testroot
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler -  
rejected. filter does not include /testroot
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler -  
about sync 
file://tmp/junit13097876300643150377/junit11032106733571259963/testroot/testfile.txt
 -> jcr:///testroot/testfile.txt
14:51:02.446 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler -  
rejected. filter does not include /testroot/testfile.txt
14:51:02.447 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - Sync 
cycle completed for 
SyncSpec{fileRoot=/tmp/junit13097876300643150377/junit11032106733571259963}
{code}


was (Author: kwin):
Debug log exposes the following when the test is failing:

{code}
09:06:35.782 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
Starting sync cycle for 
SyncSpec{fileRoot=/tmp/junit14823511034048835553/junit4530291883935561564}.
09:06:35.782 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
Scanning filesystem for changes 
SyncSpec{fileRoot=/tmp/junit14823511034048835553/junit4530291883935561564}
09:06:35.783 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - Sync 
cycle completed for 
SyncSpec{fileRoot=/tmp/junit14823511034048835553/junit4530291883935561564}
[INFO] Tests run: 96, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 25.70 s 
-- in org.apache.jackrabbit.vault.packaging.integration.SubPackagesIT
09:06:36.034 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
Starting sync cycle for 
SyncSpec{fileRoot=/tmp/junit14823511034048835553/junit4530291883935561564}.
09:06:36.034 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
Scanning filesystem for changes 
SyncSpec{fileRoot=/tmp/junit14823511034048835553/junit4530291883935561564}
09:06:36.036 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - Sync 
cycle completed for 
SyncSpec{fileRoot=/tmp/junit14823511034048835553/junit4530291883935561564}
09:06:36.287 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
Starting sync cycle for 
SyncSpec{fileRoot=/tmp/junit14823511034048835553/junit4530291883935561564}.
09:06:36.287 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
Scanning filesystem for changes 
SyncSpec{fileRoot=/tmp/junit14823511034048835553/junit4530291883935561564}
09:06:36.289 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - Sync 
cycle completed for 
SyncSpec{fileRoot=/tmp/junit14823511034048835553/junit4530291883935561564}
09:06:36.540 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
Starting sync cycle for 
SyncSpec{fileRoot=/tmp/junit14823511034048835553/junit4530291883935561564}.
09:06:36.540 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
Scanning filesystem for changes 
SyncSpec{fileRoot=/tmp/junit14823511034048835553/junit4530291883935561564}
09:06:36.542 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - Sync 
cycle completed for 
SyncSpec{fileRoot=/tmp/junit14823511034048835553/junit4530291883935561564}
09:06:36.792 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
Starting sync cycle for 
SyncSpec{fileRoot=/tmp/junit14823511034048835553/junit4530291883935561564}.
09:06:36.793 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - 
Scanning filesystem for changes 
SyncSpec{fileRoot=/tmp/junit14823511034048835553/junit4530291883935561564}
09:06:36.795 [Vault Sync Thread] DEBUG o.a.j.vault.sync.impl.SyncHandler - Sync 
cycle completed for