'Alfresco started' twice each restart - SOLVED

[mike54]

Hi,

Generally my system is rather functional right now, but I get this strange thing:
when restarting Tomcat, Alfresco.log contains "Alfresco started" entry twice.
It starts fine and than it starts again…? That does not look "healthy"
I'm bit afraid to leave it like this, I'm not sure if that did not ruin my content store once and share site became corrupted.

Alfresco log itself does not contain any errors, I have found one in Catalina.out - I don't know if it is related…?

14:15:04,208 User:System INFO  [service.descriptor.DescriptorService] Alfresco JVM - v1.6.0_06-b02; maximum heap size 508.063MB
14:15:04,210 User:System INFO  [service.descriptor.DescriptorService] Alfresco started (Community): Current version 3.2.0 (2039) schema 2019 - Installed version 3.0.0 (c 1342) schema 1000
Oct 8, 2009 2:15:29 PM org.apache.catalina.core.NamingContextListener addResource
WARNING: Failed to register in JMX: javax.naming.NamingException: Could not create resource factory instance [Root exception is java.lang.ClassNotFoundException: org.apache.tomcat.dbcp.dbcp.BasicDataSourceFactory]
14:15:32,677  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/repository.properties]‍‍‍‍‍

Could it be something wrong with my Tomcat configuration, that it starts the same application twice?

System: Centos 5.3 + OpenOffice 3.1 + Apache HTTPD + Tomcat 5 running Alfresco 3.2

Please advise
Thanks!
Mike

[gronfelt]

I don't think it's related, the warning is probably beca use you lack a file in Tomcat:

/tomcat/lib/tomcat-dbcp.jar

If you have installed Tomcat with yum, that may be the cause of that file not existing (if that's the problem).

Regarding the problem with Alfresco starting twice I don't know what may cause that, how does the log look?

[mike54]

Log looks ok to me, except for the double start…
This is how it looks after single restart (log was clean before restart)

15:07:33,372 INFO  [org.alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/repository.properties]
15:07:33,375 INFO  [org.alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/domain/transaction.properties]
15:07:33,375 INFO  [org.alfresco.config.JndiPropertiesFactoryBean] Loading properties file from URL [file:/var/lib/tomcat5/shared/classes/alfresco-global.properties]
15:07:33,454 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:07:52,744 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Schema managed by database dialect org.hibernate.dialect.MySQLInnoDBDialect.
15:07:55,052 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] No changes were made to the schema.
15:07:58,225 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'thirdparty' subsystem, ID: [default]
15:07:58,296 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:08:00,324 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'thirdparty' subsystem, ID: [default] complete
15:08:00,538 INFO  [org.alfresco.repo.admin.ConfigurationChecker] The Alfresco root data directory ('dir.root') is: /var/lib/alfresco/alf_data
15:08:00,612 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] Checking for patches to apply …
15:08:01,241 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] No patches were required.
15:08:01,248 INFO  [org.alfresco.repo.module.ModuleServiceImpl] Found 0 module(s).
15:08:01,309 WARN  [org.alfresco.repo.module.ModuleServiceImpl] A previously-installed module 'org.alfresco.module.RecordsManagement' (version 1.3) is not present in your distribution.
15:08:01,332 WARN  [org.alfresco.repo.module.ModuleServiceImpl] A previously-installed module 'org.alfresco.module.vti' (version 1.0) is not present in your distribution.
15:08:01,359 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'fileServers' subsystem, ID: [default]
15:08:01,393 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:08:02,455 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'Authentication' subsystem, ID: [managed, alfrescoNtlm1]
15:08:02,482 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:08:02,876 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'Authentication' subsystem, ID: [managed, alfrescoNtlm1] complete
15:08:08,115 ERROR [org.alfresco.fileserver] Failed to get local domain/workgroup name, using default of WORKGROUP
15:08:08,116 ERROR [org.alfresco.fileserver] (This may be due to firewall settings or incorrect <broadcast> setting)
15:08:08,205 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'fileServers' subsystem, ID: [default] complete
15:08:08,206 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'imap' subsystem, ID: [default]
15:08:08,227 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:08:08,296 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'imap' subsystem, ID: [default] complete
15:08:08,300 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'Synchronization' subsystem, ID: [default]
15:08:08,326 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:08:08,337 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'Synchronization' subsystem, ID: [default] complete
15:08:08,464 INFO  [org.alfresco.service.descriptor.DescriptorService] Alfresco JVM - v1.6.0_06-b02; maximum heap size 508.063MB


15:08:08,465 INFO  [org.alfresco.service.descriptor.DescriptorService] Alfresco started (Community): Current version 3.2.0 (2039) schema 2019 - Installed version 3.0.0 (c 1342) schema 1000


15:08:35,910 INFO  [org.alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/repository.properties]
15:08:35,912 INFO  [org.alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/domain/transaction.properties]
15:08:35,913 INFO  [org.alfresco.config.JndiPropertiesFactoryBean] Loading properties file from URL [file:/var/lib/tomcat5/shared/classes/alfresco-global.properties]
15:08:35,967 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:08:56,993 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] Schema managed by database dialect org.hibernate.dialect.MySQLInnoDBDialect.
15:08:58,714 INFO  [org.alfresco.repo.domain.schema.SchemaBootstrap] No changes were made to the schema.
15:09:02,741 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'thirdparty' subsystem, ID: [default]
15:09:02,783 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:09:03,857 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'thirdparty' subsystem, ID: [default] complete
15:09:04,119 INFO  [org.alfresco.repo.admin.ConfigurationChecker] The Alfresco root data directory ('dir.root') is: /var/lib/alfresco/alf_data
15:09:04,200 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] Checking for patches to apply …
15:09:04,930 INFO  [org.alfresco.repo.admin.patch.PatchExecuter] No patches were required.
15:09:04,936 INFO  [org.alfresco.repo.module.ModuleServiceImpl] Found 0 module(s).
15:09:05,000 WARN  [org.alfresco.repo.module.ModuleServiceImpl] A previously-installed module 'org.alfresco.module.RecordsManagement' (version 1.3) is not present in your distribution.
15:09:05,024 WARN  [org.alfresco.repo.module.ModuleServiceImpl] A previously-installed module 'org.alfresco.module.vti' (version 1.0) is not present in your distribution.
15:09:05,033 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'fileServers' subsystem, ID: [default]
15:09:05,063 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:09:05,539 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'Authentication' subsystem, ID: [managed, alfrescoNtlm1]
15:09:05,560 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:09:05,953 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'Authentication' subsystem, ID: [managed, alfrescoNtlm1] complete
15:09:11,122 ERROR [org.alfresco.fileserver] Failed to get local domain/workgroup name, using default of WORKGROUP
15:09:11,123 ERROR [org.alfresco.fileserver] (This may be due to firewall settings or incorrect <broadcast> setting)
15:09:11,215 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'fileServers' subsystem, ID: [default] complete
15:09:11,216 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'imap' subsystem, ID: [default]
15:09:11,244 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:09:11,310 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'imap' subsystem, ID: [default] complete
15:09:11,312 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Starting 'Synchronization' subsystem, ID: [default]
15:09:11,377 INFO  [org.alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
15:09:11,406 INFO  [org.alfresco.repo.management.subsystems.ChildApplicationContextFactory] Startup of 'Synchronization' subsystem, ID: [default] complete
15:09:11,473 INFO  [org.alfresco.service.descriptor.DescriptorService] Alfresco JVM - v1.6.0_06-b02; maximum heap size 508.063MB


15:09:11,475 INFO  [org.alfresco.service.descriptor.DescriptorService] Alfresco started (Community): Current version 3.2.0 (2039) schema 2019 - Installed version 3.0.0 (c 1342) schema 1000‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

[mike54]

I don't think it's related, the warning is probably beca use you lack a file in Tomcat:
/tomcat/lib/tomcat-dbcp.jar
If you have installed Tomcat with yum, that may be the cause of that file not existing (if that's the problem).

yes, I have installed it using yum, ant the file is not there, but I don't see any easy way of fixing that…
I have found some info here:
http://tomcat.apache.org/tomcat-5.5-doc/jndi-datasource-examples-howto.html

I have this file
tomcat5/common/lib/[commons-dbcp].jar
but I don't know if it is of any use…

[mike54]

ok, I have decided for a radical step - I will install binary distribution of Tomcat from Apache.org

Second thing - Alfresco gets started twice as Tomcat will first start all applications located inside webapps and than all applications indicated by Context element. I have added Context element as I need it for matching with HTTPD host.

I did get such remarks from helpful people :
deployOnStartup is deploying the affresco dir as "/alfresco" when Tomcat starts.
Set deployOnStartup to false

—- OR delete the above context element and rename "/var/lib/tomcat5/webapps/alfresco" to "/var/lib/tomcat5/webapps/ROOT"

—- OR
Remove Context entry and restart tomcat. If you - for any reason - need Context element, move webapp to folder different than webapps and update docBase.