AnsweredAssumed Answered

SMB server crashes with memory error after 1 hour

Question asked by macwestsolutions on Jun 6, 2010
Latest reply on Jun 7, 2010 by mrogers
If have just installed Alfresco-Enterprise-3.3-MacOS-X-Install on Snow Leopard Server version 10.6.3.

The only changes I have made to the install are the adding the following lines to the ${installdir}/tomcat/shared/classes/alfresco-global.properties

ftp.enable=false
filesystem.name=Alfresco
cifs.localname=${localname}
cifs.enabled=true
cifs.serverName=alfresco
cifs.domain=MACWEST
cifs.hostanounce=true
cifs.broadcast=10.1.1.255
cifs.bindto=10.1.1.8
cifs.ipv6.enabled=false

I start the alfresco service as root so the smb service can bind to the ports
$sudo /Users/admin/Alfresco/alfresco.sh start

Once started I can mount and access the alfresco shared OK from my Mac clients.

But after a period of about an hour the smb server will crash with a memory error. This will happen even if I just start Alfresco and the do nothing else but wait an hour. If I restart the service then everything will be ok again for about an hour then it will crash again. Once it's started smbclient will generate the following;

northwest:~ admin$ smbclient -L 10.1.1.8
Password:
Domain=[ALFRESCO] OS=[Java] Server=[Alfresco CIFS Server 5.1.0]

   Sharename       Type      Comment
   ———       —-      ——-
cli_rpc_pipe_open_noauth: rpc_pipe_bind for pipe \srvsvc failed with error NT_STATUS_BUFFER_TOO_SMALL
^C
northwest:~ admin$ smbclient -L 10.1.1.8
Password:
Domain=[ALFRESCO] OS=[Java] Server=[Alfresco CIFS Server 5.1.0]

   Sharename       Type      Comment
   ———       —-      ——-
cli_rpc_pipe_open_noauth: rpc_pipe_bind for pipe \srvsvc failed with error NT_STATUS_BUFFER_TOO_SMALL
   Alfresco        Disk     
   IPC$            IPC      
   AVM             Disk     
Domain=[ALFRESCO] OS=[Java] Server=[Alfresco CIFS Server 5.1.0]

   Server               Comment
   ———            ——-

   Workgroup            Master
   ———            ——-
northwest:~

The following is the catalina.out file from start to error;



northwest:Alfresco admin$ tail -f ./tomcat/logs/catalina.out
Jun 6, 2010 1:24:43 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 922 ms
Jun 6, 2010 1:24:43 PM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
Jun 6, 2010 1:24:43 PM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/6.0.26
Jun 6, 2010 1:24:43 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor alfresco.xml
Jun 6, 2010 1:24:47 PM 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.
13:25:03,189  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/repository.properties]
13:25:03,216  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from class path resource [alfresco/domain/transaction.properties]
13:25:03,225  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/Users/admin/Alfresco/tomcat/webapps/alfresco/WEB-INF/classes/alfresco/module/test/alfresco-global.properties]
13:25:03,232  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from file [/Users/admin/Alfresco/tomcat/webapps/alfresco/WEB-INF/classes/alfresco/module/tests/alfresco-global.properties]
13:25:03,234  INFO  [alfresco.config.JndiPropertiesFactoryBean] Loading properties file from URL [file:/Users/admin/Alfresco/tomcat/shared/classes/alfresco-global.properties]
13:25:03,340  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
13:25:34,315  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor Repository Template Processor for extension ftl
13:25:34,319  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor Repository Script Processor for extension js
13:25:43,582  INFO  [domain.schema.SchemaBootstrap] Schema managed by database dialect org.hibernate.dialect.MySQLInnoDBDialect.
13:25:45,803  INFO  [domain.schema.SchemaBootstrap] No changes were made to the schema.
13:25:46,105  DEBUG [alfresco.repo.googledocs] preRegister called. Server=com.sun.jmx.mbeanserver.JmxMBeanServer@4139eeda, name=log4j:logger=org.alfresco.repo.googledocs
13:25:46,162  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'sysAdmin' subsystem, ID: [sysAdmin, default]
13:25:46,196  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
13:25:46,220  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'sysAdmin' subsystem, ID: [sysAdmin, default] complete
13:25:48,638  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'thirdparty' subsystem, ID: [thirdparty, default]
13:25:48,665  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
13:25:50,723  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'thirdparty' subsystem, ID: [thirdparty, default] complete
13:25:50,724  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'OOoDirect' subsystem, ID: [OOoDirect, default]
13:25:50,878  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
13:25:51,813  WARN  [alfresco.util.OpenOfficeConnectionTester] An initial OpenOffice connection could not be established.
13:25:51,829  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'OOoDirect' subsystem, ID: [OOoDirect, default] complete
13:25:51,829  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default]
13:25:51,918  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
13:25:52,169  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'OOoJodconverter' subsystem, ID: [OOoJodconverter, default] complete
13:25:58,003  INFO  [repo.admin.ConfigurationChecker] The Alfresco root data directory ('dir.root') is: /Users/admin/Alfresco/alf_data
13:25:58,290  INFO  [admin.patch.PatchExecuter] Checking for patches to apply …
13:25:58,861  INFO  [admin.patch.PatchExecuter] No patches were required.
13:25:59,068 User:System INFO  [repo.module.ModuleServiceImpl] Found 1 module(s).
13:25:59,166 User:System INFO  [repo.module.ModuleServiceImpl] Starting module 'org.alfresco.module.vti' version 1.2.
13:25:59,280  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'fileServers' subsystem, ID: [fileServers, default]
13:25:59,363  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
13:26:00,326  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1]
13:26:00,414  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
13:26:00,848  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Authentication' subsystem, ID: [Authentication, managed, alfrescoNtlm1] complete
13:26:01,182  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'fileServers' subsystem, ID: [fileServers, default] complete
13:26:01,196  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'imap' subsystem, ID: [imap, default]
13:26:01,341  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
13:26:01,976  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'imap' subsystem, ID: [imap, default] complete
13:26:01,977  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'email' subsystem, ID: [email, outbound]
13:26:02,257  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
13:26:02,659  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'email' subsystem, ID: [email, outbound] complete
13:26:02,660  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'email' subsystem, ID: [email, inbound]
13:26:02,718  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
13:26:02,956  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)]
13:26:02,956  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)]
13:26:03,224  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'email' subsystem, ID: [email, inbound] complete
13:26:03,224  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'googledocs' subsystem, ID: [googledocs, default]
13:26:03,328  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
13:26:05,244  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'googledocs' subsystem, ID: [googledocs, default] complete
13:26:05,250  INFO  [repo.usage.UserUsageTrackingComponent] Enabled - calculate missing user usages …
13:26:05,317  INFO  [repo.usage.UserUsageTrackingComponent] Found 0 users to recalculate
13:26:05,318  INFO  [repo.usage.UserUsageTrackingComponent] … calculated missing usages for 0 users
13:26:05,319  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'Synchronization' subsystem, ID: [Synchronization, default]
13:26:05,363  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
13:26:05,468  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
13:26:05,704  INFO  [service.descriptor.DescriptorService] Alfresco JVM - v1.6.0_20-b02-279-10M3065; maximum heap size 910.250MB
13:26:06,420  INFO  [service.descriptor.DescriptorService] Alfresco license: Enterprise - v3.3 granted to Trial User limited to 30 days expiring Sun Jul 04 00:00:00 WST 2010 (28 days remaining)
13:26:06,421  INFO  [service.descriptor.DescriptorService] Alfresco started (Enterprise): Current version 3.3.0 (64) schema 4010 - Originally installed version 3.3.0 (64) schema 4010
13:26:13,394  INFO  [module.vti.VtiServer] Vti server started successfully on port: 7070
13:26:27,854 User:System INFO  [extensions.webscripts.DeclarativeRegistry] Registered 349 Web Scripts (+0 failed), 585 URLs
13:26:27,865 User:System INFO  [extensions.webscripts.DeclarativeRegistry] Registered 2 Package Description Documents (+0 failed)
13:26:27,865 User:System INFO  [extensions.webscripts.DeclarativeRegistry] Registered 1 Schema Description Documents (+0 failed)
13:26:27,868 User:System INFO  [extensions.webscripts.AbstractRuntimeContainer] Initialised Repository Web Script Container (in 13871.145ms)
13:26:27,868  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension ftl
13:26:27,869  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js
13:26:28,134  INFO  [management.subsystems.ChildApplicationContextFactory] Starting 'wcm_deployment_receiver' subsystem, ID: [wcm_deployment_receiver, default]
13:26:28,189  INFO  [alfresco.config.JndiPropertyPlaceholderConfigurer] Loading properties file from class path resource [alfresco/alfresco-shared.properties]
13:26:28,310  INFO  [management.subsystems.ChildApplicationContextFactory] Startup of 'wcm_deployment_receiver' subsystem, ID: [wcm_deployment_receiver, default] complete
Jun 6, 2010 1:26:29 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor host-manager.xml
Jun 6, 2010 1:26:29 PM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor manager.xml
Jun 6, 2010 1:26:30 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive share.war
13:26:36,452  INFO  [extensions.webscripts.DeclarativeRegistry] Registered 229 Web Scripts (+0 failed), 237 URLs
13:26:36,466  INFO  [extensions.webscripts.DeclarativeRegistry] Registered 8 Package Description Documents (+0 failed)
13:26:36,466  INFO  [extensions.webscripts.DeclarativeRegistry] Registered 0 Schema Description Documents (+0 failed)
13:26:36,585  INFO  [extensions.webscripts.AbstractRuntimeContainer] Initialised Spring Surf Container Web Script Container (in 1863.075ms)
13:26:36,670  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension ftl
13:26:36,786  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js
13:26:36,847  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension ftl
13:26:36,851  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js
13:26:36,942  INFO  [extensions.webscripts.TemplateProcessorRegistry] Registered template processor freemarker for extension ftl
13:26:36,946  INFO  [extensions.webscripts.ScriptProcessorRegistry] Registered script processor javascript for extension js
Jun 6, 2010 1:26:38 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory docs
Jun 6, 2010 1:26:38 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory examples
Jun 6, 2010 1:26:38 PM org.apache.coyote.http11.Http11Protocol start
INFO: Starting Coyote HTTP/1.1 on http-8080
Jun 6, 2010 1:26:38 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 114739 ms
13:27:00,602  INFO  [alfresco.util.OpenOfficeConnectionTester] The OpenOffice connection was re-established.
14:13:40,390  ERROR [org.alfresco.fileserver] org.alfresco.jlan.server.core.NoPooledMemoryException: Request size 65535
14:13:40,410  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.CIFSPacketPool.allocatePacket(CIFSPacketPool.java:114)
14:13:40,410  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.CIFSPacketPool.allocatePacket(CIFSPacketPool.java:154)
14:13:40,410  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.SMBSrvTransPacket.doTransactionResponse(SMBSrvTransPacket.java:593)
14:13:40,410  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.SMBSrvTransPacket.doTransactionResponse(SMBSrvTransPacket.java:544)
14:13:40,410  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.PipeLanmanHandler.procNetShareEnum(PipeLanmanHandler.java:341)
14:13:40,410  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.PipeLanmanHandler.processRequest(PipeLanmanHandler.java:114)
14:13:40,411  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.IPCHandler.procTransaction(IPCHandler.java:156)
14:13:40,411  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.NTProtocolHandler.procTransact2(NTProtocolHandler.java:1589)
14:13:40,411  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.NTProtocolHandler.runProtocol(NTProtocolHandler.java:232)
14:13:40,411  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.SMBSrvSession.runHandler(SMBSrvSession.java:1366)
14:13:40,411  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.SMBSrvSession.processPacket(SMBSrvSession.java:1458)
14:13:40,411  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.nio.NIOCIFSThreadRequest.runRequest(NIOCIFSThreadRequest.java:105)
14:13:40,411  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.server.thread.ThreadRequestPool$ThreadWorker.run(ThreadRequestPool.java:153)
14:13:40,411  ERROR [org.alfresco.fileserver]    at java.lang.Thread.run(Thread.java:637)
14:13:40,411  ERROR [org.alfresco.fileserver] org.alfresco.jlan.server.core.NoPooledMemoryException: Request size 65535
14:13:40,441  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.CIFSPacketPool.allocatePacket(CIFSPacketPool.java:114)
14:13:40,441  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.CIFSPacketPool.allocatePacket(CIFSPacketPool.java:154)
14:13:40,441  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.SMBSrvTransPacket.doTransactionResponse(SMBSrvTransPacket.java:593)
14:13:40,442  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.SMBSrvTransPacket.doTransactionResponse(SMBSrvTransPacket.java:544)
14:13:40,442  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.PipeLanmanHandler.procNetShareEnum(PipeLanmanHandler.java:341)
14:13:40,442  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.PipeLanmanHandler.processRequest(PipeLanmanHandler.java:114)
14:13:40,442  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.IPCHandler.procTransaction(IPCHandler.java:156)
14:13:40,442  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.NTProtocolHandler.procTransact2(NTProtocolHandler.java:1589)
14:13:40,442  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.NTProtocolHandler.runProtocol(NTProtocolHandler.java:232)
14:13:40,442  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.SMBSrvSession.runHandler(SMBSrvSession.java:1366)
14:13:40,442  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.SMBSrvSession.processPacket(SMBSrvSession.java:1458)
14:13:40,442  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.smb.server.nio.NIOCIFSThreadRequest.runRequest(NIOCIFSThreadRequest.java:105)
14:13:40,442  ERROR [org.alfresco.fileserver]    at org.alfresco.jlan.server.thread.ThreadRequestPool$ThreadWorker.run(ThreadRequestPool.java:153)
14:13:40,443  ERROR [org.alfresco.fileserver]    at java.lang.Thread.run(Thread.java:637)

Outcomes