AnsweredAssumed Answered

Alfresco 3.2 : 06230004 Failed to prepare: requires rollback

Question asked by rgouyet on Jul 23, 2009
Latest reply on Aug 24, 2009 by rgouyet
Bonjour,

Comme à chaque nouvelle version d'Alfresco Community, je fais des tests pour vérifier si je peux utiliser cette version.
J'ai donc installé la 3.2 community dans un environnement windows, base mysql.
Installation de base, aucune modification.
Je me connecte en admin/admin.
J'upload un fichier word (.doc), premier écran, je spécifie l'emplacement de mon fichier, je valide
Message 'xxxx.doc' was uploaded successfully.
Je clique sur OK puis message d'erreur  :
  Please correct the errors below then click OK.
    * A system error happened during the operation: 06230010 Failed to prepare: requires rollback

Dans le log en ne conservant que certaines lignes pertinentes :
14:40:08,697 ERROR [org.alfresco.web.ui.common.Utils] A system error happened during the operation: 06230010 Failed to prepare: requires rollback
org.alfresco.repo.search.IndexerException: 06230010 Failed to prepare: requires rollback
Caused by: org.alfresco.repo.search.IndexerException: 06230009 Invalid state MARKED_ROLLBACK


Dans le log complet avec debug sur lucene :

14:40:08,166 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Got C:\Alfresco\alf_data\lucene-indexes\user\alfrescoUserStore org.alfresco.repo.search.impl.lucene.index.IndexInfo@486e486e for C:\Alfresco\alf_data\lucene-indexes\user\alfrescoUserStore
14:40:08,166 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - http-8080-12
14:40:08,166 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - http-8080-12 -  in 3.529E-4 ms
14:40:08,166 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader MainReader - increment - ref count is 1        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@72fe72fe
14:40:08,166 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Main index reader references = 1
14:40:08,166 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - http-8080-12
14:40:08,166 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader MainReader closing        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@72fe72fe
14:40:08,166 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader MainReader - decrement - ref count is 0        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@72fe72fe
14:40:08,166 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader MainReader still open …. ref = 0 invalidForReuse = false        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@72fe72fe
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Got C:\Alfresco\alf_data\lucene-indexes\workspace\SpacesStore org.alfresco.repo.search.impl.lucene.index.IndexInfo@37803780 for C:\Alfresco\alf_data\lucene-indexes\workspace\SpacesStore
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - http-8080-12
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - http-8080-12 -  in 3.739E-4 ms
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - http-8080-12
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for WRITE lock  - http-8080-12
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT WRITE LOCK  - http-8080-12 -  in 3.644E-4 ms
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Start Index 092732e7-3de5-4310-9269-b5475621b942 state = ACTIVE
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=3243bac1-fd73-48cd-92eb-6dbebdddf516 Type=INDEX Status=COMMITTED Docs=1198 Deletions=0
2         Name=46f7b9f4-6351-4d7f-b7dc-84397d4dad9e Type=INDEX Status=COMMITTED Docs=4 Deletions=0
3         Name=f3e09ef5-1465-4fd0-9754-f2f52b2932cd Type=INDEX Status=COMMITTED Docs=9 Deletions=0
4         Name=48969dee-53db-4760-b736-6cd1c3109eab Type=INDEX Status=COMMITTED Docs=21 Deletions=0
5         Name=e7c85b45-0794-45ad-bd51-ea24f9e8328b Type=INDEX Status=COMMITTED Docs=3 Deletions=0
6         Name=ceec4985-38f4-4b4e-9520-13ad5d08d355 Type=DELTA Status=COMMITTED Docs=1 Deletions=1
7         Name=443f5d4d-4428-4efc-abf2-19718c0c1f9e Type=DELTA Status=COMMITTED Docs=1 Deletions=1

14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] End Index 092732e7-3de5-4310-9269-b5475621b942 state = ACTIVE
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=3243bac1-fd73-48cd-92eb-6dbebdddf516 Type=INDEX Status=COMMITTED Docs=1198 Deletions=0
2         Name=46f7b9f4-6351-4d7f-b7dc-84397d4dad9e Type=INDEX Status=COMMITTED Docs=4 Deletions=0
3         Name=f3e09ef5-1465-4fd0-9754-f2f52b2932cd Type=INDEX Status=COMMITTED Docs=9 Deletions=0
4         Name=48969dee-53db-4760-b736-6cd1c3109eab Type=INDEX Status=COMMITTED Docs=21 Deletions=0
5         Name=e7c85b45-0794-45ad-bd51-ea24f9e8328b Type=INDEX Status=COMMITTED Docs=3 Deletions=0
6         Name=ceec4985-38f4-4b4e-9520-13ad5d08d355 Type=DELTA Status=COMMITTED Docs=1 Deletions=1
7         Name=443f5d4d-4428-4efc-abf2-19718c0c1f9e Type=DELTA Status=COMMITTED Docs=1 Deletions=1
8         Name=092732e7-3de5-4310-9269-b5475621b942 Type=DELTA Status=ACTIVE Docs=0 Deletions=0

14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - http-8080-12
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - http-8080-12 -  in 3.644E-4 ms
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED WRITE LOCK  - http-8080-12
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - http-8080-12
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - http-8080-12
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - http-8080-12 -  in 1.794E-4 ms
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - http-8080-12
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for WRITE lock  - http-8080-12
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT WRITE LOCK  - http-8080-12 -  in 2.035E-4 ms
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Start Index 092732e7-3de5-4310-9269-b5475621b942 state = ACTIVE
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=3243bac1-fd73-48cd-92eb-6dbebdddf516 Type=INDEX Status=COMMITTED Docs=1198 Deletions=0
2         Name=46f7b9f4-6351-4d7f-b7dc-84397d4dad9e Type=INDEX Status=COMMITTED Docs=4 Deletions=0
3         Name=f3e09ef5-1465-4fd0-9754-f2f52b2932cd Type=INDEX Status=COMMITTED Docs=9 Deletions=0
4         Name=48969dee-53db-4760-b736-6cd1c3109eab Type=INDEX Status=COMMITTED Docs=21 Deletions=0
5         Name=e7c85b45-0794-45ad-bd51-ea24f9e8328b Type=INDEX Status=COMMITTED Docs=3 Deletions=0
6         Name=ceec4985-38f4-4b4e-9520-13ad5d08d355 Type=DELTA Status=COMMITTED Docs=1 Deletions=1
7         Name=443f5d4d-4428-4efc-abf2-19718c0c1f9e Type=DELTA Status=COMMITTED Docs=1 Deletions=1
8         Name=092732e7-3de5-4310-9269-b5475621b942 Type=DELTA Status=ACTIVE Docs=0 Deletions=0

14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] End Index 092732e7-3de5-4310-9269-b5475621b942 state = ACTIVE
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=3243bac1-fd73-48cd-92eb-6dbebdddf516 Type=INDEX Status=COMMITTED Docs=1198 Deletions=0
2         Name=46f7b9f4-6351-4d7f-b7dc-84397d4dad9e Type=INDEX Status=COMMITTED Docs=4 Deletions=0
3         Name=f3e09ef5-1465-4fd0-9754-f2f52b2932cd Type=INDEX Status=COMMITTED Docs=9 Deletions=0
4         Name=48969dee-53db-4760-b736-6cd1c3109eab Type=INDEX Status=COMMITTED Docs=21 Deletions=0
5         Name=e7c85b45-0794-45ad-bd51-ea24f9e8328b Type=INDEX Status=COMMITTED Docs=3 Deletions=0
6         Name=ceec4985-38f4-4b4e-9520-13ad5d08d355 Type=DELTA Status=COMMITTED Docs=1 Deletions=1
7         Name=443f5d4d-4428-4efc-abf2-19718c0c1f9e Type=DELTA Status=COMMITTED Docs=1 Deletions=1
8         Name=092732e7-3de5-4310-9269-b5475621b942 Type=DELTA Status=ACTIVE Docs=0 Deletions=0

14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - http-8080-12
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - http-8080-12 -  in 3.344E-4 ms
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED WRITE LOCK  - http-8080-12
14:40:08,494 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - http-8080-12
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - http-8080-12
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - http-8080-12 -  in 3.013E-4 ms
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - http-8080-12
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for WRITE lock  - http-8080-12
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT WRITE LOCK  - http-8080-12 -  in 2.076E-4 ms
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Start Index 092732e7-3de5-4310-9269-b5475621b942 state = PREPARING
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=3243bac1-fd73-48cd-92eb-6dbebdddf516 Type=INDEX Status=COMMITTED Docs=1198 Deletions=0
2         Name=46f7b9f4-6351-4d7f-b7dc-84397d4dad9e Type=INDEX Status=COMMITTED Docs=4 Deletions=0
3         Name=f3e09ef5-1465-4fd0-9754-f2f52b2932cd Type=INDEX Status=COMMITTED Docs=9 Deletions=0
4         Name=48969dee-53db-4760-b736-6cd1c3109eab Type=INDEX Status=COMMITTED Docs=21 Deletions=0
5         Name=e7c85b45-0794-45ad-bd51-ea24f9e8328b Type=INDEX Status=COMMITTED Docs=3 Deletions=0
6         Name=ceec4985-38f4-4b4e-9520-13ad5d08d355 Type=DELTA Status=COMMITTED Docs=1 Deletions=1
7         Name=443f5d4d-4428-4efc-abf2-19718c0c1f9e Type=DELTA Status=COMMITTED Docs=1 Deletions=1
8         Name=092732e7-3de5-4310-9269-b5475621b942 Type=DELTA Status=ACTIVE Docs=0 Deletions=0

14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] End Index 092732e7-3de5-4310-9269-b5475621b942 state = PREPARING
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=3243bac1-fd73-48cd-92eb-6dbebdddf516 Type=INDEX Status=COMMITTED Docs=1198 Deletions=0
2         Name=46f7b9f4-6351-4d7f-b7dc-84397d4dad9e Type=INDEX Status=COMMITTED Docs=4 Deletions=0
3         Name=f3e09ef5-1465-4fd0-9754-f2f52b2932cd Type=INDEX Status=COMMITTED Docs=9 Deletions=0
4         Name=48969dee-53db-4760-b736-6cd1c3109eab Type=INDEX Status=COMMITTED Docs=21 Deletions=0
5         Name=e7c85b45-0794-45ad-bd51-ea24f9e8328b Type=INDEX Status=COMMITTED Docs=3 Deletions=0
6         Name=ceec4985-38f4-4b4e-9520-13ad5d08d355 Type=DELTA Status=COMMITTED Docs=1 Deletions=1
7         Name=443f5d4d-4428-4efc-abf2-19718c0c1f9e Type=DELTA Status=COMMITTED Docs=1 Deletions=1
8         Name=092732e7-3de5-4310-9269-b5475621b942 Type=DELTA Status=PREPARING Docs=0 Deletions=0

14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - http-8080-12
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - http-8080-12 -  in 3.088E-4 ms
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED WRITE LOCK  - http-8080-12
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - http-8080-12
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - http-8080-12
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - http-8080-12 -  in 1.664E-4 ms
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - http-8080-12
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for WRITE lock  - http-8080-12
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT WRITE LOCK  - http-8080-12 -  in 0.001006 ms
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader 3243bac1-fd73-48cd-92eb-6dbebdddf516 - increment - ref count is 1        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@3e043e04
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader 46f7b9f4-6351-4d7f-b7dc-84397d4dad9e - increment - ref count is 1        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@75e075e
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader f3e09ef5-1465-4fd0-9754-f2f52b2932cd - increment - ref count is 1        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@7aae7aae
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader 48969dee-53db-4760-b736-6cd1c3109eab - increment - ref count is 1        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@5fd45fd4
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader e7c85b45-0794-45ad-bd51-ea24f9e8328b - increment - ref count is 1        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@43684368
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader ceec4985-38f4-4b4e-9520-13ad5d08d355 - increment - ref count is 1        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@36003600
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] There are 1 deletions for ceec4985-38f4-4b4e-9520-13ad5d08d355
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader 443f5d4d-4428-4efc-abf2-19718c0c1f9e - increment - ref count is 1        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@576c576c
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] There are 1 deletions for 443f5d4d-4428-4efc-abf2-19718c0c1f9e
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory] Replacing ref counting reader for MainReader
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory] Created ref counting reader for MainReader org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@17441744
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - http-8080-12
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - http-8080-12 -  in 3.033E-4 ms
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED WRITE LOCK  - http-8080-12
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader MainReader - increment - ref count is 1        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@17441744
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Main index reader references = 1
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - http-8080-12
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - http-8080-12
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - http-8080-12 -  in 0.0014607 ms
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - http-8080-12
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - http-8080-12
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - http-8080-12 -  in 3.363E-4 ms
14:40:08,572 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - http-8080-12
14:40:08,603 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader MainReader closing        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@17441744
14:40:08,603 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader MainReader - decrement - ref count is 0        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@17441744
14:40:08,603 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader MainReader still open …. ref = 0 invalidForReuse = false        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@17441744
14:40:08,681 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - http-8080-12
14:40:08,681 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - http-8080-12 -  in 3.213E-4 ms
14:40:08,681 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - http-8080-12
14:40:08,681 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for WRITE lock  - http-8080-12
14:40:08,681 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT WRITE LOCK  - http-8080-12 -  in 2.186E-4 ms
14:40:08,681 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Start Index 092732e7-3de5-4310-9269-b5475621b942 state = ROLLINGBACK
14:40:08,681 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=3243bac1-fd73-48cd-92eb-6dbebdddf516 Type=INDEX Status=COMMITTED Docs=1198 Deletions=0
2         Name=46f7b9f4-6351-4d7f-b7dc-84397d4dad9e Type=INDEX Status=COMMITTED Docs=4 Deletions=0
3         Name=f3e09ef5-1465-4fd0-9754-f2f52b2932cd Type=INDEX Status=COMMITTED Docs=9 Deletions=0
4         Name=48969dee-53db-4760-b736-6cd1c3109eab Type=INDEX Status=COMMITTED Docs=21 Deletions=0
5         Name=e7c85b45-0794-45ad-bd51-ea24f9e8328b Type=INDEX Status=COMMITTED Docs=3 Deletions=0
6         Name=ceec4985-38f4-4b4e-9520-13ad5d08d355 Type=DELTA Status=COMMITTED Docs=1 Deletions=1
7         Name=443f5d4d-4428-4efc-abf2-19718c0c1f9e Type=DELTA Status=COMMITTED Docs=1 Deletions=1
8         Name=092732e7-3de5-4310-9269-b5475621b942 Type=DELTA Status=PREPARING Docs=0 Deletions=0

14:40:08,681 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] End Index 092732e7-3de5-4310-9269-b5475621b942 state = ROLLINGBACK
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=3243bac1-fd73-48cd-92eb-6dbebdddf516 Type=INDEX Status=COMMITTED Docs=1198 Deletions=0
2         Name=46f7b9f4-6351-4d7f-b7dc-84397d4dad9e Type=INDEX Status=COMMITTED Docs=4 Deletions=0
3         Name=f3e09ef5-1465-4fd0-9754-f2f52b2932cd Type=INDEX Status=COMMITTED Docs=9 Deletions=0
4         Name=48969dee-53db-4760-b736-6cd1c3109eab Type=INDEX Status=COMMITTED Docs=21 Deletions=0
5         Name=e7c85b45-0794-45ad-bd51-ea24f9e8328b Type=INDEX Status=COMMITTED Docs=3 Deletions=0
6         Name=ceec4985-38f4-4b4e-9520-13ad5d08d355 Type=DELTA Status=COMMITTED Docs=1 Deletions=1
7         Name=443f5d4d-4428-4efc-abf2-19718c0c1f9e Type=DELTA Status=COMMITTED Docs=1 Deletions=1
8         Name=092732e7-3de5-4310-9269-b5475621b942 Type=DELTA Status=ROLLINGBACK Docs=0 Deletions=0

14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - http-8080-12
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - http-8080-12 -  in 2.22E-4 ms
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED WRITE LOCK  - http-8080-12
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - http-8080-12
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - http-8080-12
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - http-8080-12 -  in 1.573E-4 ms
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory] Created ref counting reader for 092732e7-3de5-4310-9269-b5475621b942 org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@49b049b0
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - http-8080-12
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for WRITE lock  - http-8080-12
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT WRITE LOCK  - http-8080-12 -  in 2.541E-4 ms
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Start Index 092732e7-3de5-4310-9269-b5475621b942 state = ROLLEDBACK
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=3243bac1-fd73-48cd-92eb-6dbebdddf516 Type=INDEX Status=COMMITTED Docs=1198 Deletions=0
2         Name=46f7b9f4-6351-4d7f-b7dc-84397d4dad9e Type=INDEX Status=COMMITTED Docs=4 Deletions=0
3         Name=f3e09ef5-1465-4fd0-9754-f2f52b2932cd Type=INDEX Status=COMMITTED Docs=9 Deletions=0
4         Name=48969dee-53db-4760-b736-6cd1c3109eab Type=INDEX Status=COMMITTED Docs=21 Deletions=0
5         Name=e7c85b45-0794-45ad-bd51-ea24f9e8328b Type=INDEX Status=COMMITTED Docs=3 Deletions=0
6         Name=ceec4985-38f4-4b4e-9520-13ad5d08d355 Type=DELTA Status=COMMITTED Docs=1 Deletions=1
7         Name=443f5d4d-4428-4efc-abf2-19718c0c1f9e Type=DELTA Status=COMMITTED Docs=1 Deletions=1
8         Name=092732e7-3de5-4310-9269-b5475621b942 Type=DELTA Status=ROLLINGBACK Docs=0 Deletions=0

14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Removed rollback
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] f3e09ef5-1465-4fd0-9754-f2f52b2932cd is still part of the index
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] ceec4985-38f4-4b4e-9520-13ad5d08d355 is still part of the index
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] e7c85b45-0794-45ad-bd51-ea24f9e8328b is still part of the index
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] 443f5d4d-4428-4efc-abf2-19718c0c1f9e is still part of the index
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] 46f7b9f4-6351-4d7f-b7dc-84397d4dad9e is still part of the index
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] 3243bac1-fd73-48cd-92eb-6dbebdddf516 is still part of the index
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] 092732e7-3de5-4310-9269-b5475621b942 is now INVALID
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] 48969dee-53db-4760-b736-6cd1c3109eab is still part of the index
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] … invalidating sub reader 092732e7-3de5-4310-9269-b5475621b942
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader 092732e7-3de5-4310-9269-b5475621b942 set invalid for reuse        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@49b049b0
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader 092732e7-3de5-4310-9269-b5475621b942 closed.        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@49b049b0
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] … invalidating main index reader
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader MainReader set invalid for reuse        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@17441744
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader MainReader closed.        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@17441744
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader 3243bac1-fd73-48cd-92eb-6dbebdddf516 closing        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@3e043e04
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader 3243bac1-fd73-48cd-92eb-6dbebdddf516 - decrement - ref count is 0        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@3e043e04
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader 3243bac1-fd73-48cd-92eb-6dbebdddf516 still open …. ref = 0 invalidForReuse = false        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@3e043e04
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader 46f7b9f4-6351-4d7f-b7dc-84397d4dad9e closing        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@75e075e
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader 46f7b9f4-6351-4d7f-b7dc-84397d4dad9e - decrement - ref count is 0        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@75e075e
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader 46f7b9f4-6351-4d7f-b7dc-84397d4dad9e still open …. ref = 0 invalidForReuse = false        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@75e075e
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader f3e09ef5-1465-4fd0-9754-f2f52b2932cd closing        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@7aae7aae
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader f3e09ef5-1465-4fd0-9754-f2f52b2932cd - decrement - ref count is 0        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@7aae7aae
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader f3e09ef5-1465-4fd0-9754-f2f52b2932cd still open …. ref = 0 invalidForReuse = false        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@7aae7aae
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader 48969dee-53db-4760-b736-6cd1c3109eab closing        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@5fd45fd4
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader 48969dee-53db-4760-b736-6cd1c3109eab - decrement - ref count is 0        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@5fd45fd4
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader 48969dee-53db-4760-b736-6cd1c3109eab still open …. ref = 0 invalidForReuse = false        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@5fd45fd4
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader e7c85b45-0794-45ad-bd51-ea24f9e8328b closing        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@43684368
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader e7c85b45-0794-45ad-bd51-ea24f9e8328b - decrement - ref count is 0        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@43684368
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader e7c85b45-0794-45ad-bd51-ea24f9e8328b still open …. ref = 0 invalidForReuse = false        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@43684368
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader ceec4985-38f4-4b4e-9520-13ad5d08d355 closing        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@36003600
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader ceec4985-38f4-4b4e-9520-13ad5d08d355 - decrement - ref count is 0        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@36003600
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader ceec4985-38f4-4b4e-9520-13ad5d08d355 still open …. ref = 0 invalidForReuse = false        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@36003600
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader 443f5d4d-4428-4efc-abf2-19718c0c1f9e closing        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@576c576c
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader 443f5d4d-4428-4efc-abf2-19718c0c1f9e - decrement - ref count is 0        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@576c576c
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader] http-8080-12: Reader 443f5d4d-4428-4efc-abf2-19718c0c1f9e still open …. ref = 0 invalidForReuse = false        … org.alfresco.repo.search.impl.lucene.index.ReferenceCountingReadOnlyIndexReaderFactory$ReferenceCountingReadOnlyIndexReader@576c576c
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] End Index 092732e7-3de5-4310-9269-b5475621b942 state = ROLLEDBACK
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Deleting no longer referenced 092732e7-3de5-4310-9269-b5475621b942
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo]
Entry List
1         Name=3243bac1-fd73-48cd-92eb-6dbebdddf516 Type=INDEX Status=COMMITTED Docs=1198 Deletions=0
2         Name=46f7b9f4-6351-4d7f-b7dc-84397d4dad9e Type=INDEX Status=COMMITTED Docs=4 Deletions=0
3         Name=f3e09ef5-1465-4fd0-9754-f2f52b2932cd Type=INDEX Status=COMMITTED Docs=9 Deletions=0
4         Name=48969dee-53db-4760-b736-6cd1c3109eab Type=INDEX Status=COMMITTED Docs=21 Deletions=0
5         Name=e7c85b45-0794-45ad-bd51-ea24f9e8328b Type=INDEX Status=COMMITTED Docs=3 Deletions=0
6         Name=ceec4985-38f4-4b4e-9520-13ad5d08d355 Type=DELTA Status=COMMITTED Docs=1 Deletions=1
7         Name=443f5d4d-4428-4efc-abf2-19718c0c1f9e Type=DELTA Status=COMMITTED Docs=1 Deletions=1

14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] … queued delete for 092732e7-3de5-4310-9269-b5475621b942
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - http-8080-12
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] … Id = 092732e7-3de5-4310-9269-b5475621b942 Invalid = 0 invalid = true closed=true
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - http-8080-12 -  in 2.781E-4 ms
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Waiting for READ lock  - indexThread9
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED WRITE LOCK  - http-8080-12
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - http-8080-12
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] …GOT READ LOCK  - indexThread9 -  in 0.0062079 ms
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] RELEASED READ LOCK  - indexThread9
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] Expunging 092732e7-3de5-4310-9269-b5475621b942 remaining 1
14:40:08,697 DEBUG [org.alfresco.repo.search.impl.lucene.index.IndexInfo] … Id = 092732e7-3de5-4310-9269-b5475621b942 Invalid = 0 invalid = true closed=true
14:40:08,697 ERROR [org.alfresco.web.ui.common.Utils] A system error happened during the operation: 06230010 Failed to prepare: requires rollback
org.alfresco.repo.search.IndexerException: 06230010 Failed to prepare: requires rollback
   at org.alfresco.repo.search.impl.lucene.AbstractLuceneIndexerAndSearcherFactory.prepare(AbstractLuceneIndexerAndSearcherFactory.java:797)
   at org.alfresco.repo.transaction.AlfrescoTransactionSupport$TransactionSynchronizationImpl.beforeCommit(AlfrescoTransactionSupport.java:667)
   at org.springframework.transaction.support.TransactionSynchronizationUtils.triggerBeforeCommit(TransactionSynchronizationUtils.java:48)
   at org.springframework.transaction.support.AbstractPlatformTransactionManager.triggerBeforeCommit(AbstractPlatformTransactionManager.java:835)
   at org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:645)
   at org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:632)
   at org.springframework.transaction.interceptor.TransactionAspectSupport.commitTransactionAfterReturning(TransactionAspectSupport.java:314)
   at org.alfresco.util.transaction.SpringAwareUserTransaction.commit(SpringAwareUserTransaction.java:467)
   at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:340)
   at org.alfresco.web.bean.dialog.BaseDialogBean.finish(BaseDialogBean.java:130)
   at org.alfresco.web.bean.dialog.DialogManager.finish(DialogManager.java:534)
   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:64)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   at java.lang.reflect.Method.invoke(Method.java:615)
   at org.apache.myfaces.el.MethodBindingImpl.invoke(MethodBindingImpl.java:132)
   at org.apache.myfaces.application.ActionListenerImpl.processAction(ActionListenerImpl.java:61)
   at javax.faces.component.UICommand.broadcast(UICommand.java:109)
   at javax.faces.component.UIViewRoot._broadcastForPhase(UIViewRoot.java:97)
   at javax.faces.component.UIViewRoot.processApplication(UIViewRoot.java:171)
   at org.apache.myfaces.lifecycle.InvokeApplicationExecutor.execute(InvokeApplicationExecutor.java:32)
   at org.apache.myfaces.lifecycle.LifecycleImpl.executePhase(LifecycleImpl.java:95)
   at org.apache.myfaces.lifecycle.LifecycleImpl.execute(LifecycleImpl.java:70)
   at javax.faces.webapp.FacesServlet.service(FacesServlet.java:139)
   at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
   at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
   at org.alfresco.web.app.servlet.AuthenticationFilter.doFilter(AuthenticationFilter.java:110)
   at sun.reflect.GeneratedMethodAccessor520.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   at java.lang.reflect.Method.invoke(Method.java:615)
   at org.alfresco.repo.management.subsystems.ChainingSubsystemProxyFactory$1.invoke(ChainingSubsystemProxyFactory.java:122)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
   at $Proxy191.doFilter(Unknown Source)
   at org.alfresco.repo.web.filter.beans.BeanProxyFilter.doFilter(BeanProxyFilter.java:88)
   at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
   at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
   at org.alfresco.repo.web.filter.beans.NullFilter.doFilter(NullFilter.java:74)
   at sun.reflect.GeneratedMethodAccessor520.invoke(Unknown Source)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   at java.lang.reflect.Method.invoke(Method.java:615)
   at org.alfresco.repo.management.subsystems.ChainingSubsystemProxyFactory$1.invoke(ChainingSubsystemProxyFactory.java:122)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
   at $Proxy191.doFilter(Unknown Source)
   at org.alfresco.repo.web.filter.beans.BeanProxyFilter.doFilter(BeanProxyFilter.java:88)
   at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
   at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
   at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
   at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
   at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
   at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
   at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
   at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:286)
   at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:845)
   at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583)
   at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
   at java.lang.Thread.run(Thread.java:801)
Caused by: org.alfresco.repo.search.IndexerException: 06230009 Invalid state MARKED_ROLLBACK
   at org.alfresco.repo.search.impl.lucene.index.IndexInfo.getTransition(IndexInfo.java:1324)
   at org.alfresco.repo.search.impl.lucene.index.IndexInfo.setStatus(IndexInfo.java:1247)
   at org.alfresco.repo.search.impl.lucene.AbstractLuceneBase.setStatus(AbstractLuceneBase.java:257)
   at org.alfresco.repo.search.impl.lucene.AbstractLuceneIndexerImpl.setRollbackOnly(AbstractLuceneIndexerImpl.java:550)
   at org.alfresco.repo.search.impl.lucene.AbstractLuceneIndexerImpl.prepare(AbstractLuceneIndexerImpl.java:485)
   at org.alfresco.repo.search.impl.lucene.AbstractLuceneIndexerAndSearcherFactory.prepare(AbstractLuceneIndexerAndSearcherFactory.java:792)
   … 57 more


Au final mon document est bien présent et son contenu est accessible.
En plus, il semble bien indexé

Mais bon, c'est tout de même pas normal ce message.

J'ai essayé avec la nightly build du 14/07 et c'est la même chose.

Suis-je le seul ?

Outcomes