Hyland Community
Help
cancel
Turn on suggestions
Auto-suggest helps you quickly narrow down your search results by suggesting possible matches as you type.
Showing results for 
Search instead for 
Did you mean: 

[RESOLU]Lenteur du démarrage Alfresco

kimblue
Champ in-the-making
Champ in-the-making

‎06-01-2010 05:12 AM

Bonjour,

Version Alfresco : 3.2r2
MySQL : 5.1.47

Mon problème est que depuis que j'ai installé la nouvelle version de MySQL mon application met plus de 2h à démarrer :'( !!!
Le processus java prend 200% du CPU !!!

Je ne comprend pas pourquoi! Et je n'ai pas d'erreur dans la log 😞

16:21:37,991 INFO  [org.alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/repository.properties]
16:21:37,993 INFO  [org.alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/domain/transaction.properties]
16:21:37,993 INFO  [org.alfresco.config.JndiPropertiesFactoryBean] Loading properties file from URL [file:/produit/alfresco/tomcat/shared/classes/alfresco-global.properties]
16:21:38,065 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
16:21:42,958 INFO  [org.alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/produit/alfresco/tomcat/shared/classes/alfresco/extension/subsystems/Authentication/ldap-ad/ldap-ad1/ldap-ad-authentication.properties]
16:21:50,453 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Schema managed by database dialect org.hibernate.dialect.MySQLInnoDBDialect.
16:21:52,924 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] No changes were made to the schema.
16:21:53,885 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
16:21:53,895 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
16:21:53,899 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
16:22:03,073 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent] Index recovery started: 43,235 transactions.
16:22:04,618 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'thirdparty' subsystem, ID: [thirdparty, default]
16:22:04,695 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
16:22:05,223 WARN  [org.alfresco.util.OpenOfficeConnectionTester] An initial OpenOffice connection could not be established.
16:22:05,224 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'thirdparty' subsystem, ID: [thirdparty, default] complete
16:22:18,911 ERROR [org.alfresco.repo.node.index.AbstractReindexComponent.threads] Reindexer     3 failed with error: 04310001 Failed to prepare: requires rollback.
16:22:18,945 ERROR [org.alfresco.repo.node.index.AbstractReindexComponent.threads] Reindexer     5 failed with error: 04310003 Failed to prepare: requires rollback.
16:22:26,997 ERROR [org.alfresco.repo.node.index.AbstractReindexComponent.threads] Reindexer     7 failed with error: 04310005 Failed to prepare: requires rollback.
16:24:52,883 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    10 % complete.
16:27:53,500 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    20 % complete.
16:31:41,111 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    30 % complete.
16:38:31,194 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    40 % complete.
16:47:08,379 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    50 % complete.
16:56:54,721 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    60 % complete.
17:14:00,462 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    70 % complete.
17:33:29,453 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    80 % complete.
18:04:32,752 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    90 % complete.
19:17:54,387 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent]    100 % complete.
19:33:34,384 INFO  [org.alfresco.repo.node.index.FullIndexRecoveryComponent] Index recovery completed.
19:33:34,401 INFO  [org.alfresco.repo.node.index.AVMFullIndexRecoveryComponent] Rebuilding indexes for 1 AVM Stores
19:33:34,401 INFO  [org.alfresco.repo.node.index.AVMFullIndexRecoveryComponent]     Rebuilding index for sitestore
19:33:34,478 INFO  [org.alfresco.repo.node.index.AVMFullIndexRecoveryComponent]   Reindex   100% complete
19:33:34,479 INFO  [org.alfresco.repo.node.index.AVMFullIndexRecoveryComponent] Finished rebuilding indexes for AVM Stores
19:33:34,661 INFO  [org.alfresco.repo.admin.ConfigurationChecker] The Alfresco root data directory ('dir.root') is: /data/alfresco/alf_data
19:33:34,674 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] Checking for patches to apply …
19:33:34,825 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] No patches were required.
19:33:34,844 INFO  [org.alfresco.repo.module.ModuleServiceImpl] Found 0 module(s).
19:33:34,977 WARN  [org.alfresco.repo.module.ModuleServiceImpl] A previously-installed module 'org.alfresco.module.vti' (version 1.2) is not present in your distribution.
19:33:34,990 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'fileServers' subsystem, ID: [fileServers, default]
19:33:35,022 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
19:33:35,333 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'fileServers' subsystem, ID: [fileServers, default] complete
19:33:35,333 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'imap' subsystem, ID: [imap, default]
19:33:35,351 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
19:33:35,422 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'imap' subsystem, ID: [imap, default] complete
19:33:35,422 INFO  [org.alfresco.repo.usage.UserUsageTrackingComponent] Enabled - calculate missing user usages …
19:33:35,642 INFO  [org.alfresco.repo.usage.UserUsageTrackingComponent] Found 0 users to recalculate
19:33:35,642 INFO  [org.alfresco.repo.usage.UserUsageTrackingComponent] … calculated missing usages for 0 users
19:33:35,642 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'Synchronization' subsystem, ID: [Synchronization, default]
19:33:35,653 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
19:33:35,672 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'Authentication' subsystem, ID: [Authentication, managed, ldap-ad1]
19:33:35,710 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
19:33:35,881 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'Authentication' subsystem, ID: [Authentication, managed, ldap-ad1] complete
19:33:35,881 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
19:33:35,898 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
19:33:36,017 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1] complete
19:33:36,017 INFO  [org.alfresco.repo.security.sync.ChainingUserRegistrySynchronizer] Synchronizing users and groups with user registry 'ldap-ad1'
19:33:36,022 INFO  [org.alfresco.repo.security.sync.ChainingUserRegistrySynchronizer] Retrieving groups changed since May 31, 2010 11:57:18 AM from user registry 'ldap-ad1'
19:33:36,078 INFO  [org.alfresco.repo.security.sync.ChainingUserRegistrySynchronizer] ldap-ad1 Group Analysis: Commencing batch of 0 entries
19:33:36,080 INFO  [org.alfresco.repo.security.sync.ChainingUserRegistrySynchronizer] ldap-ad1 Group Analysis: Completed batch of 0 entries
19:33:36,083 INFO  [org.alfresco.repo.security.sync.ChainingUserRegistrySynchronizer] Retrieving users changed since May 31, 2010 11:59:41 AM from user registry 'ldap-ad1'
19:33:36,111 INFO  [org.alfresco.repo.security.sync.ChainingUserRegistrySynchronizer] ldap-ad1 User Creation and Association: Commencing batch of 12 entries
‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

Quelqu'un pourrait m'aider ?

Merci d'avance
Labels:
0 Kudos
5 REPLIES 5

rguinot
Confirmed Champ
Confirmed Champ

‎06-01-2010 07:52 AM

Dans le log que vous avez fourni, vous faites une reconstruction complète des indexes, d'ou la longueur au démarrage.
plusieurs causes possibles :
- vous l'avez explicitement demandé via (index.recovery.mode=FULL) dans alfresco-global.properties ou repository.properties :
- vous avez des indexes corrompus, pour lesquels il manque au moins le reflet des  10 premières transactions, et il déclenche de lui même un rebuild full.
0 Kudos

kimblue
Champ in-the-making
Champ in-the-making

‎06-01-2010 08:32 AM

Bonjour,

Merci pour votre retour.

Oui effectivement dans ma configuration j'ai mis la réindexation en mode FULL. Ca va faire un mois que j'ai installé Alfresco et je n'avais pas eu ce problème. Jusqu'à hier avec la montée de version de MySQL.

Là le redémarrage a bien planté : 

CompilerOracle: exclude org/apache/lucene/index/IndexReader$1.doBody
CompilerOracle: exclude org/alfresco/repo/search/impl/lucene/index/IndexInfo$Merger.mergeIndexes
CompilerOracle: exclude org/alfresco/repo/search/impl/lucene/index/IndexInfo$Merger.mergeDeletions
1 juin 2010 10:41:32 org.apache.coyote.http11.Http11Protocol init
INFO: Initialisation de Coyote HTTP/1.1 sur http-8080
1 juin 2010 10:41:32 org.apache.coyote.http11.Http11Protocol init
INFO: Initialisation de Coyote HTTP/1.1 sur http-8443
1 juin 2010 10:41:32 org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 828 ms
1 juin 2010 10:41:32 org.apache.catalina.core.StandardService start
INFO: D�marrage du service Catalina
1 juin 2010 10:41:32 org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/6.0.18
1 juin 2010 10:41:33 org.apache.catalina.core.StandardContext addApplicationListener
INFO: The listener "org.apache.myfaces.webapp.StartupServletContextListener" is already configured for this context. The duplicate definition has been ignored.
10:41:36,644  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/repository.properties]
10:41:36,645  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/domain/transaction.properties]
10:41:36,646  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from URL [file:/produit/alfresco/tomcat/shared/classes/alfresco-global.properties]
10:41:36,718  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:41:41,616  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/produit/alfresco/tomcat/shared/classes/alfresco/extension/subsystems/Authentication/ldap-ad/ldap-ad1/ldap-ad-authentication.properties]
10:41:48,945  INFO  [domain.schema.SchemaBootstrap] Schema managed by database dialect org.hibernate.dialect.MySQLInnoDBDialect.
10:41:51,338  INFO  [domain.schema.SchemaBootstrap] No changes were made to the schema.
10:41:52,231  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
10:41:52,241  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:41:52,244  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
### Excluding compile: org.alfresco.repo.search.impl.lucene.index.IndexInfo$Merger::mergeDeletions
10:50:13,709  INFO  [node.index.FullIndexRecoveryComponent] Index recovery started: 43 238 transactions.
10:50:15,081  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'thirdparty' subsystem, ID: [thirdparty, default]
10:50:15,162  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
10:50:15,865  WARN  [alfresco.util.OpenOfficeConnectionTester] An initial OpenOffice connection could not be established.
10:50:15,865  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'thirdparty' subsystem, ID: [thirdparty, default] complete
10:58:44,458  ERROR [index.AbstractReindexComponent.threads] Reindexer     3 failed with error: 05010001 Failed to prepare: requires rollback.
10:58:44,542  ERROR [index.AbstractReindexComponent.threads] Reindexer     5 failed with error: 05010003 Failed to prepare: requires rollback.
11:24:25,679  ERROR [index.AbstractReindexComponent.threads] Reindexer    11 failed with error: 05010007 Failed to prepare: requires rollback.
11:32:38,659  ERROR [index.AbstractReindexComponent.threads] Reindexer    13 failed with error: 05010005 Failed to prepare: requires rollback.
12:59:32,270  INFO  [node.index.FullIndexRecoveryComponent] 10 % complete.
13:26:34,749  ERROR [lucene.index.IndexInfo] Index merger failed to recover - suspending
java.lang.RuntimeException: Error during run with lock.
        at org.alfresco.repo.search.impl.lucene.index.IndexInfo.doWithFileLock(IndexInfo.java:2331)
        at org.alfresco.repo.search.impl.lucene.index.IndexInfo.access$4700(IndexInfo.java:144)
        at org.alfresco.repo.search.impl.lucene.index.IndexInfo$Merger.recoverImpl(IndexInfo.java:3241)
        at org.alfresco.repo.search.impl.lucene.index.IndexInfo$AbstractSchedulable.run(IndexInfo.java:3083)
        at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
        at java.lang.Thread.run(Thread.java:619)
Caused by: java.io.FileNotFoundException: /data/alfresco/alf_data/lucene-indexes/workspace/SpacesStore/01fc8117-d1d9-4934-9d91-2ee933b634a1/_0.frq (No such file or directory)
        at java.io.RandomAccessFile.open(Native Method)
        at java.io.RandomAccessFile.<init>(RandomAccessFile.java:212)
        at org.apache.lucene.store.FSDirectory$FSIndexInput$Descriptor.<init>(FSDirectory.java:552)
        at org.apache.lucene.store.FSDirectory$FSIndexInput.<init>(FSDirectory.java:582)
        at org.apache.lucene.store.FSDirectory.openInput(FSDirectory.java:488)
        at org.apache.lucene.store.FSDirectory.openInput(FSDirectory.java:482)
        at org.apache.lucene.store.Directory.copy(Directory.java:190)
        at org.apache.lucene.store.RAMDirectory.<init>(RAMDirectory.java:73)
        at org.apache.lucene.store.RAMDirectory.<init>(RAMDirectory.java:84)
        at org.alfresco.repo.search.impl.lucene.index.IndexInfo.buildReferenceCountingIndexReader(IndexInfo.java:2002)
        at org.alfresco.repo.search.impl.lucene.index.IndexInfo.access$1200(IndexInfo.java:144)
        at org.alfresco.repo.search.impl.lucene.index.IndexInfo$Merger$2.doWork(IndexInfo.java:3300)
        at org.alfresco.repo.search.impl.lucene.index.IndexInfo.doWithFileLock(IndexInfo.java:2400)
        at org.alfresco.repo.search.impl.lucene.index.IndexInfo.doWithFileLock(IndexInfo.java:2320)
        … 6 more
13:26:34,893  ERROR [lucene.index.IndexInfo] Index merger has recovered - resuming …

‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

Dois-je changer la conf de la réindexation ?

Merci d'avance.
0 Kudos

rguinot
Confirmed Champ
Confirmed Champ

‎06-01-2010 08:44 AM

votre platefome ne m'a pas l'air très propre : 


[quote]10:58:44,458  ERROR [index.AbstractReindexComponent.threads] Reindexer     3 failed with error: 05010001 Failed to prepare: requires rollback.
10:58:44,542  ERROR [index.AbstractReindexComponent.threads] Reindexer     5 failed with error: 05010003 Failed to prepare: requires rollback.
11:24:25,679  ERROR [index.AbstractReindexComponent.threads] Reindexer    11 failed with error: 05010007 Failed to prepare: requires rollback.
11:32:38,659  ERROR [index.AbstractReindexComponent.threads] Reindexer    13 failed with error: 05010005 Failed to prepare: requires rollback.
[/quote]‍‍‍‍‍

Caused by: java.io.FileNotFoundException: /data/alfresco/alf_data/lucene-indexes/workspace/SpacesStore/01fc8117-d1d9-4934-9d91-2ee933b634a1/_0.frq (No such file or directory)
        at java.io.RandomAccessFile.open(Native Method)
‍‍‍


Avez vous supprimé vos indexes ? si vous ne disposez de backup, il vous faudra les reconstruire (après avoir vidé complètement le contenu du repertoire lucene-indexes). une fois reconstruit, vous devez replacer l'index.recovery.mode a VALIDATE ou a AUTO.
0 Kudos

kimblue
Champ in-the-making
Champ in-the-making

‎06-01-2010 09:03 AM

Je suis d'accord avec vous sur le fait que ma plateforme n'est pas propre : 

[quote]10:58:44,458  ERROR [index.AbstractReindexComponent.threads] Reindexer     3 failed with error: 05010001 Failed to prepare: requires rollback.
10:58:44,542  ERROR [index.AbstractReindexComponent.threads] Reindexer     5 failed with error: 05010003 Failed to prepare: requires rollback.
11:24:25,679  ERROR [index.AbstractReindexComponent.threads] Reindexer    11 failed with error: 05010007 Failed to prepare: requires rollback.
11:32:38,659  ERROR [index.AbstractReindexComponent.threads] Reindexer    13 failed with error: 05010005 Failed to prepare: requires rollback.
[/quote]‍‍‍‍‍

Je n'ai pas encore trouver le moyen de faire disparaître cette erreur 😞

Pour l'autre erreur : 

Caused by: java.io.FileNotFoundException: /data/alfresco/alf_data/lucene-indexes/workspace/SpacesStore/01fc8117-d1d9-4934-9d91-2ee933b634a1/_0.frq (No such file or directory)
        at java.io.RandomAccessFile.open(Native Method)
‍‍‍

Je n'ai rien supprimé du tout. Le seul changement c'est la montée de version de MySQL.

Je vais essayer de remonter les données avec mon dump.
0 Kudos

kimblue
Champ in-the-making
Champ in-the-making

‎06-01-2010 10:51 AM

En fait certain fichier avait pour propriétaire root et non l'utilisateur d'alfresco Smiley Sad

J'ai changé les droits des fichiers et alfresco démarre mieux Smiley Happy

Bon j'ai d'autre erreurs dans la log que je vais investiguer!

Merci Smiley Happy
0 Kudos
Getting started

Tags

Find what you came for

We want to make your experience in Hyland Connect as valuable as possible, so we put together some helpful links.

Copyright © 2025 Khoros, LLC