[ 
https://issues.apache.org/jira/browse/JCRVLT-730?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=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: 
> fs=/tmp/junit6534468439393939733/junit3629697623599693036/testroot 
> jcr=/testroot ops=UPDATE_FS
> 16:52:18.927 [Vault Sync Thread] INFO  o.a.j.vault.sync.impl.SyncResult - 
> SyncResult: 
> fs=/tmp/junit6534468439393939733/junit3629697623599693036/testroot/testfile 
> jcr=/testroot/testfile ops=UPDATE_FS
> 16:52:24.068 [Vault Sync Thread] INFO  o.a.j.vault.sync.impl.SyncLog - U 
> file:///tmp/junit6534468439393939733/junit3629697623599693036/testroot/testfile
> 16:52:24.069 [Vault Sync Thread] INFO  o.a.j.vault.sync.impl.SyncResult - 
> SyncResult: 
> fs=/tmp/junit6534468439393939733/junit3629697623599693036/testroot/testfile 
> jcr=/testroot/testfile ops=UPDATE_FS
> [ERROR] Tests run: 2, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 26.54 
> s <<< FAILURE! -- in 
> org.apache.jackrabbit.vault.sync.impl.VaultSyncServiceImplIT
> [ERROR] 
> org.apache.jackrabbit.vault.sync.impl.VaultSyncServiceImplIT.testAddRemoveFileFromNonVltCheckoutFolder
>  -- Time elapsed: 20.87 s <<< ERROR!
> org.awaitility.core.ConditionTimeoutException: Condition with lambda 
> expression in org.apache.jackrabbit.vault.sync.impl.VaultSyncServiceImplIT 
> was not fulfilled within 20 seconds.
>       at org.awaitility.core.ConditionAwaiter.await(ConditionAwaiter.java:167)
>       at 
> org.awaitility.core.CallableCondition.await(CallableCondition.java:78)
>       at 
> org.awaitility.core.CallableCondition.await(CallableCondition.java:26)
>       at org.awaitility.core.ConditionFactory.until(ConditionFactory.java:985)
>       at org.awaitility.core.ConditionFactory.until(ConditionFactory.java:954)
>       at 
> org.apache.jackrabbit.vault.sync.impl.VaultSyncServiceImplIT.testAddRemoveFileFromNonVltCheckoutFolder(VaultSyncServiceImplIT.java:75)
> {code}
> This happens on the Jenkins Ubuntu node with JDK 11 but it seems to be some 
> kind of race condition.



--
This message was sent by Atlassian Jira
(v8.20.10#820010)

Reply via email to