cancel
Showing results for 
Search instead for 
Did you mean: 

Alfresco Deployment / Start Up Hangs

smart-ebusiness
Champ in-the-making
Champ in-the-making
Hi,

I setup a new installation of Alfresco. At the runtime of the Deployment I got the following last messages

ERROR: org.alfresco.wcm.client.util.impl.GuestSessionFactoryImpl - Repository not available: Cannot access http://localhost:8080/alfresco/service/cmis: Conne$
19:08:49,145  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
19:08:49,322  INFO  [service.descriptor.DescriptorService] Alfresco JVM - v1.6.0_18-b07; maximum heap size 989.875MB
19:08:49,322  INFO  [service.descriptor.DescriptorService] Alfresco started (Community): Current version 3.4.0 (a 3169) schema 4111 - Originally installed ve$
19:08:49,352  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Replication' subsystem, ID: [Replication, default]
19:08:49,418  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
19:08:49,429  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Replication' subsystem, ID: [Replication, default] complete

Than the Deployment will not be continued and I need to stop the Tomcat hardly with a kill -9. What happens with that issues? May be somebody has a slution of that problem for me? Thank you in advacne.

Cheers, Ralf Siebert
7 REPLIES 7

smart-ebusiness
Champ in-the-making
Champ in-the-making
Hi,

I am again. I made e restart, but it hangs again. Here the last lines of the log

20:47:20,053  INFO  [repo.usage.UserUsageTrackingComponent] Enabled - calculate missing user usages …
20:47:20,120  INFO  [repo.usage.UserUsageTrackingComponent] Found 0 users to recalculate
20:47:20,120  INFO  [repo.usage.UserUsageTrackingComponent] … calculated missing usages for 0 users
20:47:20,120  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Synchronization' subsystem, ID: [Synchronization, default]
20:47:20,323  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
20:47:20,723  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
20:47:20,936  INFO  [service.descriptor.DescriptorService] Alfresco JVM - v1.6.0_18-b07; maximum heap size 989.875MB
20:47:20,937  INFO  [service.descriptor.DescriptorService] Alfresco started (Community): Current version 3.4.0 (a 3169) schema 4111 - Originally installed version 3.4.0 (a 3169) schema 4111
20:47:20,971  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Replication' subsystem, ID: [Replication, default]
20:47:21,014  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
20:47:21,026  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Replication' subsystem, ID: [Replication, default] complete

Cheers, Ralf Siebert

mrogers
Star Contributor
Star Contributor
That's showing a successful startup.

What's the problem?

smart-ebusiness
Champ in-the-making
Champ in-the-making
Hi,

thank you for the response. Yes, it looks like good. But if I want connect to the server, I am geting no resopns from the server. The tomcat is not fully started and I cannot go to the go the /share or the root directory of tomcat. This will be an clear indicator that the startup is not finished and the server hangs. May be you need another log? I have the standard installation now.

Cheers, Ralf Siebert

illu51on
Champ in-the-making
Champ in-the-making
Hi,

I'm having the same problem in my production environment and I gotta resolve this asap.  Smiley Sad  Smiley Sad
Users are waiting for the server to be up.

Help is deeply appreciated. Many thanks!

Kelvin

mrogers
Star Contributor
Star Contributor
The logs above are showing that the server has started correctly.

Are there any errors reported in the alfresco.log that you have not posted?
Are there any errors reported in Tomcat's log (catalina.log)

Could you try to give more details of what you are trying to do and what is happening.
In particular how are you trying to access Alfresco, are you using a web browser, which URLs etc …

smart-ebusiness
Champ in-the-making
Champ in-the-making
Hi,

here is my log. I let running the "hanging" alfresco 3 days and then I got some more messages. Please take a look to the attached log file.

Kind Regards,
  Ralf Siebert


—–start log—–
Oct 11, 2010 12:20:54 AM org.apache.catalina.core.AprLifecycleListener init
INFO: Loaded APR based Apache Tomcat Native library 1.1.19.
Oct 11, 2010 12:20:54 AM org.apache.catalina.core.AprLifecycleListener init
INFO: APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true].
Oct 11, 2010 12:20:55 AM org.apache.coyote.http11.Http11AprProtocol init
INFO: Initializing Coyote HTTP/1.1 on http-8989
Oct 11, 2010 12:20:55 AM org.apache.coyote.ajp.AjpAprProtocol init
INFO: Initializing Coyote AJP/1.3 on ajp-8099
Oct 11, 2010 12:20:55 AM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 1697 ms
Oct 11, 2010 12:20:55 AM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
Oct 11, 2010 12:20:55 AM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/6.0.26
Oct 11, 2010 12:20:55 AM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor host-manager.xml
Oct 11, 2010 12:20:55 AM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor alfresco.xml
Oct 11, 2010 12:21:01 AM 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.
00:21:24,170  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/repository.properties]
00:21:24,190  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/domain/transaction.properties]
00:21:24,196  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/opt/alfresco-3.4.a/tomcat/webapps/alfresco/WEB-INF/classes/alfresco/module/test/alfresco-global.properties]
00:21:24,200  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/opt/alfresco-3.4.a/tomcat/webapps/alfresco/WEB-INF/classes/alfresco/module/tests/alfresco-global.properties]
00:21:24,204  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/opt/alfresco-3.4.a/tomcat/webapps/alfresco/WEB-INF/classes/alfresco/module/org_alfresco_module_wcmquickstart/alfresco-global.properties]
00:21:24,210  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/opt/alfresco-3.4.a/tomcat/webapps/alfresco/WEB-INF/classes/alfresco/module/org_alfresco_module_dod5015/alfresco-global.properties]
00:21:24,229  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from URL [file:/opt/alfresco-3.4.a/tomcat/shared/classes/alfresco-global.properties]
00:21:24,353  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
00:22:37,195  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor Repository Template Processor for extension ftl
00:22:37,201  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor Repository Script Processor for extension js
00:22:53,549  INFO  [domain.schema.SchemaBootstrap] Schema managed by database dialect org.hibernate.dialect.MySQLInnoDBDialect.
00:22:53,866  INFO  [domain.schema.SchemaBootstrap] Executing database script /opt/alfresco-3.4.a/tomcat/temp/Alfresco/AlfrescoSchema-MySQLInnoDBDialect-Update-6944869389178602810.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoCreate-RepoTables.sql).
00:22:54,652  INFO  [domain.schema.SchemaBootstrap] Executing database script /opt/alfresco-3.4.a/tomcat/temp/Alfresco/AlfrescoSchema-MySQLInnoDBDialect-Update-3348223585751155826.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoCreate-LockTables.sql).
00:22:54,782  INFO  [domain.schema.SchemaBootstrap] Executing database script /opt/alfresco-3.4.a/tomcat/temp/Alfresco/AlfrescoSchema-MySQLInnoDBDialect-Update-1660538424110392661.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoCreate-ContentTables.sql).
00:22:54,928  INFO  [domain.schema.SchemaBootstrap] Executing database script /opt/alfresco-3.4.a/tomcat/temp/Alfresco/AlfrescoSchema-MySQLInnoDBDialect-Update-197052880846435459.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoCreate-PropertyValueTables.sql).
00:22:55,157  INFO  [domain.schema.SchemaBootstrap] Executing database script /opt/alfresco-3.4.a/tomcat/temp/Alfresco/AlfrescoSchema-MySQLInnoDBDialect-Update-1161658383299408350.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoCreate-AuditTables.sql).
00:22:55,237  INFO  [domain.schema.SchemaBootstrap] Executing database script /opt/alfresco-3.4.a/tomcat/temp/Alfresco/AlfrescoSchema-MySQLInnoDBDialect-Update-4385731492473851324.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoCreate-AvmTables.sql).
00:22:57,057  INFO  [domain.schema.SchemaBootstrap] Executing database script /opt/alfresco-3.4.a/tomcat/temp/Alfresco/AlfrescoSchema-MySQLInnoDBDialect-Update-8152098268959486034.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoCreate-ActivityTables.sql).
00:22:57,385  INFO  [domain.schema.SchemaBootstrap] Executing database script /opt/alfresco-3.4.a/tomcat/temp/Alfresco/AlfrescoSchema-MySQLInnoDBDialect-Update-1555030257940153045.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoCreate-UsageTables.sql).
00:22:58,231  INFO  [domain.schema.SchemaBootstrap] Executing database script /opt/alfresco-3.4.a/tomcat/temp/Alfresco/AlfrescoSchema-MySQLInnoDBDialect-Update-7696828760879572060.sql (Generated).
00:23:07,353  INFO  [domain.schema.SchemaBootstrap] Executing database script /opt/alfresco-3.4.a/tomcat/temp/Alfresco/AlfrescoSchema-MySQLInnoDBDialect-Update-3364801189612347645.sql (Copied from classpath:alfresco/dbscripts/create/org.hibernate.dialect.MySQLInnoDBDialect/AlfrescoPostCreate-JBPM-Extra.sql).
00:23:07,379  INFO  [domain.schema.SchemaBootstrap] All executed statements:  /opt/alfresco-3.4.a/tomcat/temp/Alfresco/AlfrescoSchema-MySQLInnoDBDialect-All_Statements-21519348026652530.sql.
00:23:13,347  INFO  [domain.schema.SchemaBootstrap] Normalized schema dumped to file /opt/alfresco-3.4.a/tomcat/temp/Alfresco/AlfrescoSchema-MySQLInnoDBDialect-222652802476708686.xml.
00:23:13,375  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
00:23:13,414  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
00:23:13,447  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
00:23:13,644 User:System WARN  [repo.dictionary.DictionaryRepositoryBootstrap] StoreRef 'workspace://SpacesStore' does not exist
00:23:22,608 User:System INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'thirdparty' subsystem, ID: [thirdparty, default]
00:23:22,676 User:System INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
00:23:23,367 User:System ERROR [transform.magick.AbstractImageMagickContentTransformerWorker] ImageMagickContentTransformerWorker not available: 09110000 Failed to perform ImageMagick transformation:
Execution result:
   os:         Linux
   command:    [/opt/alfresco-3.4.a/common/bin/convert, /opt/alfresco-3.4.a/tomcat/temp/Alfresco/ImageMagickContentTransformerWorker_init_source_213061420440054052.gif[0], /opt/alfresco-3.4.a/tomcat/temp/Alfresco/ImageMagickContentTransformerWorker_init_target_3131308762226403138.png]
   succeeded:  false
   exit code:  1
   out:       
   err:        .convert.bin: no decode delegate for this image format `/opt/alfresco-3.4.a/tomcat/temp/Alfresco/ImageMagickContentTransformerWorker_init_source_213061420440054052.gif' @ constitute.c/ReadImage/526.
.convert.bin: missing an image filename `/opt/alfre
00:23:24,045 User:System INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'thirdparty' subsystem, ID: [thirdparty, default] complete
00:23:24,058 User:System INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'OOoDirect' subsystem, ID: [OOoDirect, default]
00:23:24,242 User:System INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
00:24:17,090 User:System INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'OOoDirect' subsystem, ID: [OOoDirect, default] complete
00:25:02,420  INFO  [node.index.FullIndexRecoveryComponent] Index recovery started: 6 transactions.
00:25:02,422  INFO  [node.index.FullIndexRecoveryComponent]    10 % complete.
00:25:02,423  INFO  [node.index.FullIndexRecoveryComponent]    30 % complete.
00:25:02,423  INFO  [node.index.FullIndexRecoveryComponent]    50 % complete.
00:25:02,423  INFO  [node.index.FullIndexRecoveryComponent]    60 % complete.
00:25:02,424  INFO  [node.index.FullIndexRecoveryComponent]    80 % complete.
00:25:02,426  INFO  [node.index.FullIndexRecoveryComponent]    100 % complete.
00:25:14,163  INFO  [node.index.FullIndexRecoveryComponent] Index recovery completed.
00:25:14,170  INFO  [node.index.AVMFullIndexRecoveryComponent] Rebuilding indexes for 1 AVM Stores
00:25:14,170  INFO  [node.index.AVMFullIndexRecoveryComponent]     Rebuilding index for sitestore
00:25:14,258  INFO  [node.index.AVMFullIndexRecoveryComponent]   Reindex   100% complete
00:25:14,258  INFO  [node.index.AVMFullIndexRecoveryComponent] Finished rebuilding indexes for AVM Stores
00:25:14,944  INFO  [repo.admin.ConfigurationChecker] The Alfresco root data directory ('dir.root') is: /opt/alfresco-3.4.a/alf_data
00:25:14,961  INFO  [admin.patch.PatchExecuter] Checking for patches to apply …
00:25:18,333  WARN  [admin.patch.PatchExecuter] Patch description is not available: Patch[ id=patch.updateMimetypes2, description=patch.updateMimetypes2.description, fixesFromSchema=0, fixesToSchema=4110, targetSchema=4111]
00:25:18,344  INFO  [admin.patch.PatchExecuter]    Applying patch 'patch.wcmFolders' (Ensures the existance of the WCM specific 'Web Projects' and 'Web Forms' folders.).
00:25:18,600  INFO  [admin.patch.PatchExecuter]    Applying patch 'patch.wcmDeployed' (null).
00:25:18,641  WARN  [admin.patch.PatchExecuter] Patch description is not available: Patch[ id=patch.wcmDeployed, description=patch.wcmDeployed.description, fixesFromSchema=0, fixesToSchema=4111, targetSchema=10000]
00:25:18,836  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.4-UsageTables
RESULT:
Script completed
=====================================
00:25:18,837  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.0-ActivityTables
RESULT:
Script completed
=====================================
00:25:18,837  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.2-LockTables
RESULT:
Script completed
=====================================
00:25:18,837  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.2-ContentTables
RESULT:
Script completed
=====================================
00:25:18,838  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.2-PropertyValueTables
RESULT:
Script completed
=====================================
00:25:18,838  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.2-AvmTables
RESULT:
Script completed
=====================================
00:25:18,838  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.2-AuditTables
RESULT:
Script completed
=====================================
00:25:18,838  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.db-V3.3-JBPM-Extra
RESULT:
Script completed
=====================================
00:25:18,839  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.wcmFolders
RESULT:
The Web Projects folder was successfully created: workspace://SpacesStore/92596a3c-0a11-46f5-8439-1423bfb6d622
The Web Forms folder was successfully created: workspace://SpacesStore/92596a3c-0a11-46f5-8439-1423bfb6d622
=====================================
00:25:18,839  INFO  [admin.patch.PatchExecuter]
=== Applied patch                 ===
ID: patch.wcmDeployed
RESULT:
Imported view into bootstrap location: /app:company_home (workspace://SpacesStore/f6f0ff05-71cc-421b-9c7b-b3f20cf14410)
=====================================
00:25:18,935 User:System INFO  [repo.module.ModuleServiceImpl] Found 3 module(s).
00:25:19,040 User:System INFO  [repo.module.ModuleServiceImpl] Installing module 'org_alfresco_module_wcmquickstart' version 1.0.
00:25:19,123 User:System INFO  [repo.module.ModuleServiceImpl] Installing module 'org_alfresco_module_dod5015' version 1.0.
00:25:19,774 User:System INFO  [repo.module.ModuleServiceImpl] Installing module 'org.alfresco.module.vti' version 1.2.
00:25:20,205  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'fileServers' subsystem, ID: [fileServers, default]
00:25:20,246  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
00:25:20,723  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
00:25:20,767  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
00:25:20,951  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1] complete
00:25:20,960  WARN  [org.alfresco.fileserver] CIFS server name is longer than 15 characters, truncated to cloud-cust-svr-
00:25:26,000  ERROR [org.alfresco.fileserver] Failed to get local domain/workgroup name, using default of WORKGROUP
00:25:26,001  ERROR [org.alfresco.fileserver] (This may be due to firewall settings or incorrect <broadcast> setting)
00:25:26,169  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'fileServers' subsystem, ID: [fileServers, default] complete
00:25:26,170  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'imap' subsystem, ID: [imap, default]
00:25:26,259  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
00:25:26,359  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'imap' subsystem, ID: [imap, default] complete
00:25:26,359  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'email' subsystem, ID: [email, outbound]
00:25:26,389  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
00:25:26,487  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'email' subsystem, ID: [email, outbound] complete
00:25:26,488  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'email' subsystem, ID: [email, inbound]
00:25:26,518  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
00:25:26,594  WARN  [springframework.beans.GenericTypeAwarePropertyDescriptor] Invalid JavaBean property 'blockedSenders' being accessed! Ambiguous write methods found next to actually used [public void org.alfresco.email.server.EmailServer.setBlockedSenders(java.util.List)]: [public void org.alfresco.email.server.EmailServer.setBlockedSenders(java.lang.String)]
00:25:26,595  WARN  [springframework.beans.GenericTypeAwarePropertyDescriptor] Invalid JavaBean property 'allowedSenders' being accessed! Ambiguous write methods found next to actually used [public void org.alfresco.email.server.EmailServer.setAllowedSenders(java.util.List)]: [public void org.alfresco.email.server.EmailServer.setAllowedSenders(java.lang.String)]
00:25:26,624  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'email' subsystem, ID: [email, inbound] complete
00:25:26,625  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'googledocs' subsystem, ID: [googledocs, default]
00:25:26,680  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
00:25:28,629  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'googledocs' subsystem, ID: [googledocs, default] complete
00:25:28,630  INFO  [repo.usage.UserUsageTrackingComponent] Enabled - calculate missing user usages …
00:25:28,674  INFO  [repo.usage.UserUsageTrackingComponent] Found 0 users to recalculate
00:25:28,674  INFO  [repo.usage.UserUsageTrackingComponent] … calculated missing usages for 0 users
00:25:28,675  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Synchronization' subsystem, ID: [Synchronization, default]
00:25:28,977  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
00:25:29,196  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
00:25:29,329  INFO  [service.descriptor.DescriptorService] Alfresco JVM - v1.6.0_18-b07; maximum heap size 989.875MB
00:25:29,330  INFO  [service.descriptor.DescriptorService] Alfresco started (Community): Current version 3.4.0 (a 3169) schema 4111 - Originally installed version 3.4.0 (a 3169) schema 4111
00:25:29,354  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Replication' subsystem, ID: [Replication, default]
00:25:29,400  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
00:25:29,410  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Replication' subsystem, ID: [Replication, default] complete
—– new start —–
Oct 11, 2010 7:15:41 AM org.apache.catalina.core.AprLifecycleListener init
INFO: Loaded APR based Apache Tomcat Native library 1.1.19.
Oct 11, 2010 7:15:41 AM org.apache.catalina.core.AprLifecycleListener init
INFO: APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true].
Oct 11, 2010 7:15:42 AM org.apache.coyote.http11.Http11AprProtocol init
INFO: Initializing Coyote HTTP/1.1 on http-8989
Oct 11, 2010 7:15:42 AM org.apache.coyote.ajp.AjpAprProtocol init
INFO: Initializing Coyote AJP/1.3 on ajp-8099
Oct 11, 2010 7:15:42 AM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 1821 ms
Oct 11, 2010 7:15:42 AM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
Oct 11, 2010 7:15:42 AM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/6.0.26
Oct 11, 2010 7:15:42 AM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor host-manager.xml
Oct 11, 2010 7:15:42 AM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor alfresco.xml
Oct 11, 2010 7:15:48 AM 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.
07:16:08,107  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/repository.properties]
07:16:08,165  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/domain/transaction.properties]
07:16:08,192  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/opt/alfresco-3.4.a/tomcat/webapps/alfresco/WEB-INF/classes/alfresco/module/test/alfresco-global.properties]
07:16:08,208  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/opt/alfresco-3.4.a/tomcat/webapps/alfresco/WEB-INF/classes/alfresco/module/tests/alfresco-global.properties]
07:16:08,237  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/opt/alfresco-3.4.a/tomcat/webapps/alfresco/WEB-INF/classes/alfresco/module/org_alfresco_module_wcmquickstart/alfresco-global.properties]
07:16:08,254  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/opt/alfresco-3.4.a/tomcat/webapps/alfresco/WEB-INF/classes/alfresco/module/org_alfresco_module_dod5015/alfresco-global.properties]
07:16:08,280  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from URL [file:/opt/alfresco-3.4.a/tomcat/shared/classes/alfresco-global.properties]
07:16:08,572  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
07:17:11,978  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor Repository Template Processor for extension ftl
07:17:11,983  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor Repository Script Processor for extension js
07:17:27,381  INFO  [domain.schema.SchemaBootstrap] Schema managed by database dialect org.hibernate.dialect.MySQLInnoDBDialect.
07:17:29,105  INFO  [domain.schema.SchemaBootstrap] No changes were made to the schema.
07:17:29,518  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
07:17:29,552  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
07:17:29,581  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
07:17:35,496  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'thirdparty' subsystem, ID: [thirdparty, default]
07:17:35,533  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
07:17:36,229  ERROR [transform.magick.AbstractImageMagickContentTransformerWorker] ImageMagickContentTransformerWorker not available: 09110000 Failed to perform ImageMagick transformation:
Execution result:
   os:         Linux
   command:    [/opt/alfresco-3.4.a/common/bin/convert, /opt/alfresco-3.4.a/tomcat/temp/Alfresco/ImageMagickContentTransformerWorker_init_source_7659736486500355185.gif[0], /opt/alfresco-3.4.a/tomcat/temp/Alfresco/ImageMagickContentTransformerWorker_init_target_3525447706690554221.png]
   succeeded:  false
   exit code:  1
   out:       
   err:        .convert.bin: no decode delegate for this image format `/opt/alfresco-3.4.a/tomcat/temp/Alfresco/ImageMagickContentTransformerWorker_init_source_7659736486500355185.gif' @ constitute.c/ReadImage/526.
.convert.bin: missing an image filename `/opt/alfr
07:17:36,844  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'thirdparty' subsystem, ID: [thirdparty, default] complete
07:17:36,849  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'OOoDirect' subsystem, ID: [OOoDirect, default]
07:17:37,027  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
07:18:29,481  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'OOoDirect' subsystem, ID: [OOoDirect, default] complete
07:18:30,567  INFO  [node.index.FullIndexRecoveryComponent] Index recovery started: 69 transactions.
07:18:30,574  INFO  [node.index.FullIndexRecoveryComponent]    10 % complete.
07:18:30,575  INFO  [node.index.FullIndexRecoveryComponent]    20 % complete.
07:18:30,575  INFO  [node.index.FullIndexRecoveryComponent]    30 % complete.
07:18:30,580  INFO  [node.index.FullIndexRecoveryComponent]    40 % complete.
07:18:30,581  INFO  [node.index.FullIndexRecoveryComponent]    50 % complete.
07:18:30,581  INFO  [node.index.FullIndexRecoveryComponent]    60 % complete.
07:18:30,581  INFO  [node.index.FullIndexRecoveryComponent]    70 % complete.
07:18:30,582  INFO  [node.index.FullIndexRecoveryComponent]    80 % complete.
07:18:30,582  INFO  [node.index.FullIndexRecoveryComponent]    90 % complete.
07:18:30,587  INFO  [node.index.FullIndexRecoveryComponent]    100 % complete.
07:18:52,478  INFO  [node.index.FullIndexRecoveryComponent] Index recovery completed.
07:18:52,510  INFO  [node.index.AVMFullIndexRecoveryComponent] Rebuilding indexes for 1 AVM Stores
07:18:52,510  INFO  [node.index.AVMFullIndexRecoveryComponent]     Rebuilding index for sitestore
07:18:52,653  INFO  [node.index.AVMFullIndexRecoveryComponent]   Reindex   100% complete
07:18:52,653  INFO  [node.index.AVMFullIndexRecoveryComponent] Finished rebuilding indexes for AVM Stores
07:18:52,852  INFO  [repo.admin.ConfigurationChecker] The Alfresco root data directory ('dir.root') is: /opt/alfresco-3.4.a/alf_data
07:18:52,868  INFO  [admin.patch.PatchExecuter] Checking for patches to apply …
07:18:55,956  INFO  [admin.patch.PatchExecuter] No patches were required.
07:18:56,119 User:System INFO  [repo.module.ModuleServiceImpl] Found 3 module(s).
07:18:56,270 User:System INFO  [repo.module.ModuleServiceImpl] Starting module 'org_alfresco_module_wcmquickstart' version 1.0.
07:18:57,227 User:System INFO  [repo.module.ModuleServiceImpl] Starting module 'org_alfresco_module_dod5015' version 1.0.
07:18:57,332 User:System INFO  [repo.module.ModuleServiceImpl] Starting module 'org.alfresco.module.vti' version 1.2.
07:18:57,604  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'fileServers' subsystem, ID: [fileServers, default]
07:18:57,671  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
07:18:58,197  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
07:18:58,265  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
07:18:58,563  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1] complete
07:18:58,576  WARN  [org.alfresco.fileserver] CIFS server name is longer than 15 characters, truncated to cloud-cust-svr-
07:19:03,645  ERROR [org.alfresco.fileserver] Failed to get local domain/workgroup name, using default of WORKGROUP
07:19:03,645  ERROR [org.alfresco.fileserver] (This may be due to firewall settings or incorrect <broadcast> setting)
07:19:03,714  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'fileServers' subsystem, ID: [fileServers, default] complete
07:19:03,714  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'imap' subsystem, ID: [imap, default]
07:19:03,828  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
07:19:03,921  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'imap' subsystem, ID: [imap, default] complete
07:19:03,925  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'email' subsystem, ID: [email, outbound]
07:19:03,954  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
07:19:04,039  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'email' subsystem, ID: [email, outbound] complete
07:19:04,039  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'email' subsystem, ID: [email, inbound]
07:19:04,066  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
07:19:04,139  WARN  [springframework.beans.GenericTypeAwarePropertyDescriptor] Invalid JavaBean property 'blockedSenders' being accessed! Ambiguous write methods found next to actually used [public void org.alfresco.email.server.EmailServer.setBlockedSenders(java.util.List)]: [public void org.alfresco.email.server.EmailServer.setBlockedSenders(java.lang.String)]
07:19:04,139  WARN  [springframework.beans.GenericTypeAwarePropertyDescriptor] Invalid JavaBean property 'allowedSenders' being accessed! Ambiguous write methods found next to actually used [public void org.alfresco.email.server.EmailServer.setAllowedSenders(java.util.List)]: [public void org.alfresco.email.server.EmailServer.setAllowedSenders(java.lang.String)]
07:19:04,170  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'email' subsystem, ID: [email, inbound] complete
07:19:04,170  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'googledocs' subsystem, ID: [googledocs, default]
07:19:04,244  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
07:19:04,763  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'googledocs' subsystem, ID: [googledocs, default] complete
07:19:04,764  INFO  [repo.usage.UserUsageTrackingComponent] Enabled - calculate missing user usages …
07:19:04,788  INFO  [repo.usage.UserUsageTrackingComponent] Found 0 users to recalculate
07:19:04,789  INFO  [repo.usage.UserUsageTrackingComponent] … calculated missing usages for 0 users
07:19:04,789  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Synchronization' subsystem, ID: [Synchronization, default]
07:19:04,890  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
07:19:05,073  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
07:19:05,177  INFO  [service.descriptor.DescriptorService] Alfresco JVM - v1.6.0_18-b07; maximum heap size 989.875MB
07:19:05,177  INFO  [service.descriptor.DescriptorService] Alfresco started (Community): Current version 3.4.0 (a 3169) schema 4111 - Originally installed version 3.4.0 (a 3169) schema 4111
07:19:05,182  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Replication' subsystem, ID: [Replication, default]
07:19:05,239  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
07:19:05,248  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Replication' subsystem, ID: [Replication, default] complete
03:31:20,263  ERROR [org.alfresco.fileserver] java.io.IOException: Connection reset by peer
03:31:20,324  ERROR [org.alfresco.fileserver]    at sun.nio.ch.FileDispatcher.write0(Native Method)
03:31:20,325  ERROR [org.alfresco.fileserver]    at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:29)
03:31:20,325  ERROR [org.alfresco.fileserver]    at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:104)
03:31:20,325  ERROR [org.alfresco.fileserver]    at sun.nio.ch.IOUtil.write(IOUtil.java:75)
03:31:20,325  ERROR [org.alfresco.fileserver]    at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:334)
03:31:20,325  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.nio.ChannelPacketHandler.writeBytes(ChannelPacketHandler.java:127)
03:31:20,325  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.nio.NetBIOSSMBChannelHandler.writePacket(NetBIOSSMBChannelHandler.java:193)
03:31:20,325  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.SMBSrvSession.procNetBIOSSessionRequest(SMBSrvSession.java:900)
03:31:20,326  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.SMBSrvSession.processPacket(SMBSrvSession.java:1437)
03:31:20,326  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.nio.NIOCIFSThreadRequest.runRequest(NIOCIFSThreadRequest.java:105)
03:31:20,326  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.server.thread.ThreadRequestPool$ThreadWorker.run(ThreadRequestPool.java:153)
03:31:20,326  ERROR [org.alfresco.fileserver]    at java.lang.Thread.run(Thread.java:619)
03:31:20,327  ERROR [org.alfresco.fileserver] java.io.IOException: Connection reset by peer
03:31:20,329  ERROR [org.alfresco.fileserver]    at sun.nio.ch.FileDispatcher.write0(Native Method)
03:31:20,329  ERROR [org.alfresco.fileserver]    at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:29)
03:31:20,329  ERROR [org.alfresco.fileserver]    at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:104)
03:31:20,330  ERROR [org.alfresco.fileserver]    at sun.nio.ch.IOUtil.write(IOUtil.java:75)
03:31:20,330  ERROR [org.alfresco.fileserver]    at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:334)
03:31:20,330  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.nio.ChannelPacketHandler.writeBytes(ChannelPacketHandler.java:127)

—— and so on ——-

sanket
Champ on-the-rise
Champ on-the-rise
I faced the same problem. And finally resolved it.
Actually the secret always lies in your log file.
I got the FTP, CIFS, TCP port binding exception in the log. I changed the port numbers using the following link :

http://wiki.alfresco.com/wiki/Troubleshooting

Also I saw the statement in my log file - The indexes and database does not match.
So I went for full reindexing ( I set index.recovery.mode = FULL  in alfresco-global.properties )

Then I restarted my jboss server.

That's it.
Problem resolved.

My Envt Details :
Alfresco version - Alfresco 4.0.2 EE
App.Server - JBoss 5.1.1 EAP
Database - Oracle 11g RC2
OS - CentOS


Thanks and Regards,
Sanket Mehta.