Insufficient free space available after evicting expired cache entries. cacheMaxSize = same warning

[michaelzietlow]

/var/log/alfresco/catalina.out:

23-Jun-2021 13:31:49.462 WARNING [main] org.apache.catalina.webresources.Cache.getResources Unable to add the resource at [/WEB-INF/classes/org/springframework/extensions/surf/application/] to the cache for web application [{1}] because there was insufficient free space available after evicting expired cache entries - consider increasing the maximum size of the cache
23-Jun-2021 13:31:49.463 WARNING [main] org.apache.catalina.webresources.Cache.getResources Unable to add the resource at [/WEB-INF/classes/org/springframework/extensions/surf/override/] to the cache for web application [{1}] because there was insufficient free space available after evicting expired cache entries - consider increasing the maximum size of the cache
23-Jun-2021 13:31:49.527 WARNING [main] org.apache.catalina.webresources.Cache.getResource Unable to add the resource at [/WEB-INF/classes/] to the cache for web application [/share] because there was insufficient free space available after evicting expired cache entries - consider increasing the maximum size of the cache
23-Jun-2021 13:31:49.560 WARNING [main] org.apache.catalina.webresources.Cache.getResource Unable to add the resource at [/WEB-INF/classes/] to the cache for web application [/share] because there was insufficient free space available after evicting expired cache entries - consider increasing the maximum size of the cache

This *.Cache.getResources WARNING appears to be a common message found in catalina.out logs post-upgrade.

The Recommended solution I've read is to add "Resources cacheMaxSide" into /etc/opt/alfresco/tomcat/conf/context.xml
so it looks something like this:

<Context>
	<Resources cacheMaxSize="51200" cachingAllowed="true"/>

	<WatchedResource>WEB-INF/web.xml</WatchedResource>
	<WatchedResource>WEB-INF/tomcat-web.xml</WatchedResource>
	<WatchedResource>${catalina.base}/conf/web.xml</WatchedResource>
</Context>

THIS DOES NOTHING FOR ME.  I've even tried:

	<Resources cachingAllowed="false"/>

Is there another solution maybe? 

[cristinamr]

Hi!

Are you using tomcat 8? Check this.

Looks a strange behaviour if you disable it and the errors still appears in your logs.

Could you please try to increase the value to:

<Resources cachingAllowed="true" cacheMaxSize="300000" />

 Restart Alfresco and let us know what do you have in your logs.

Cheers.

[michaelzietlow]

Not TC8, the zip install of Community 7.0 via Ansible appears to install TOMCAT9

[root@alfresco7.0 ~]# ps -ef | grep tomcat
alfresco  7659     1 99 11:28 ?        00:03:30 /opt/openjdk-11.0.7/bin/java -Djava.util.logging.config.file=/etc/opt/alfresco/tomcat/conf/logging.properties -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager -Xms512m -Xmx3g -XX:+DisableExplicitGC -XX:+UseConcMarkSweepGC -Djava.awt.headless=true -XX:ReservedCodeCacheSize=128m -Djdk.tls.ephemeralDHKeySize=2048 -Djava.protocol.handler.pkgs=org.apache.catalina.webresources -Dorg.apache.catalina.security.SecurityListener.UMASK=0027 -Djava.net.preferIPv4Stack=true -Dacs.home=/opt/alfresco/content-services-7.0.0 -Dats-tengine-aio.host=127.0.0.1 -Dats-shared-fs.host=127.0.0.1 -Dignore.endorsed.dirs= -classpath /opt/apache-tomcat-9.0.41/bin/bootstrap.jar:/opt/apache-tomcat-9.0.41/bin/tomcat-juli.jar -Dcatalina.base=/etc/opt/alfresco/tomcat -Dcatalina.home=/opt/apache-tomcat-9.0.41 -Djava.io.tmpdir=/var/opt/alfresco/tomcat/temp org.apache.catalina.startup.Bootstrap start
root      8199 31143  0 11:29 pts/0    00:00:00 grep --color=auto tomcat

Set 

 cacheMaxSize="300000"

NO CHANGE.
  I even tried bumping it up to 600000 with the same result after a restart.  It appears as though it's cacheMaxSize is being set or limited elsewhere.

Logs below. 

community7.0, /var/log/alfresco/catalina.out:

2021-06-25 10:16:40,927 INFO [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'Synchronization' subsystem, ID: [Synchronization, default] complete
2021-06-25 10:16:40,948 INFO [service.descriptor.DescriptorService] [main] Alfresco JVM - v11.0.7+10; maximum heap size 3022.125MB
2021-06-25 10:16:40,948 INFO [service.descriptor.DescriptorService] [main] Server Mode :PRODUCTION
2021-06-25 10:16:40,952 INFO [service.descriptor.DescriptorService] [main] Alfresco Content Services started (Community). Current version: 7.0.0 (rdf924796-b1535) schema 14,002. Originally installed version: 5.0.0 (d r99759-b2) schema 8,022.
2021-06-25 10:16:40,961 INFO [management.subsystems.ChildApplicationContextFactory] [main] Starting 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default]
2021-06-25 10:16:41,188 INFO [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'ActivitiesFeed' subsystem, ID: [ActivitiesFeed, default] complete
2021-06-25 10:16:41,189 INFO [management.subsystems.ChildApplicationContextFactory] [main] Starting 'Replication' subsystem, ID: [Replication, default]
2021-06-25 10:16:41,200 INFO [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'Replication' subsystem, ID: [Replication, default] complete
2021-06-25 10:16:42,006 INFO [management.subsystems.ChildApplicationContextFactory] [main] Starting 'Messaging' subsystem, ID: [Messaging, default]
2021-06-25 10:16:43,961 INFO [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'Messaging' subsystem, ID: [Messaging, default] complete
2021-06-25 10:16:43,963 INFO [management.subsystems.ChildApplicationContextFactory] [main] Starting 'googledocs' subsystem, ID: [googledocs, drive]
2021-06-25 10:16:44,040 INFO [management.subsystems.ChildApplicationContextFactory] [main] Startup of 'googledocs' subsystem, ID: [googledocs, drive] complete
2021-06-25 10:16:46,923 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 387 Web Scripts (+0 failed), 531 URLs
2021-06-25 10:16:46,928 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed) 
2021-06-25 10:16:46,928 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed) 
2021-06-25 10:16:47,604 INFO [extensions.webscripts.DeclarativeRegistry] [main] Registered 387 Web Scripts (+0 failed), 531 URLs
2021-06-25 10:16:47,604 INFO [extensions.webscripts.DeclarativeRegistry] [main] Registered 1 Package Description Documents (+0 failed) 
2021-06-25 10:16:47,604 INFO [extensions.webscripts.DeclarativeRegistry] [main] Registered 0 Schema Description Documents (+0 failed) 
2021-06-25 10:16:47,609 INFO [extensions.webscripts.AbstractRuntimeContainer] [main] Initialised Repository Web Script Container (in 3309.1614ms)
2021-06-25 10:16:47,629 INFO [extensions.webscripts.TemplateProcessorRegistry] [main] Registered template processor freemarker for extension ftl
2021-06-25 10:16:47,631 INFO [extensions.webscripts.ScriptProcessorRegistry] [main] Registered script processor javascript for extension js
2021-06-25 10:16:48,083 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 387 Web Scripts (+0 failed), 531 URLs
2021-06-25 10:16:48,083 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 1 Package Description Documents (+0 failed) 
2021-06-25 10:16:48,083 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed) 
2021-06-25 10:16:48,170 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 14 Web Scripts (+0 failed), 103 URLs
2021-06-25 10:16:48,170 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Package Description Documents (+0 failed) 
2021-06-25 10:16:48,170 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed) 
2021-06-25 10:16:48,266 INFO [extensions.webscripts.DeclarativeRegistry] [main] Registered 14 Web Scripts (+0 failed), 103 URLs
2021-06-25 10:16:48,267 INFO [extensions.webscripts.DeclarativeRegistry] [main] Registered 0 Package Description Documents (+0 failed) 
2021-06-25 10:16:48,267 INFO [extensions.webscripts.DeclarativeRegistry] [main] Registered 0 Schema Description Documents (+0 failed) 
2021-06-25 10:16:48,269 INFO [extensions.webscripts.AbstractRuntimeContainer] [main] Initialised Public Api Web Script Container (in 627.3255ms)
2021-06-25 10:16:48,291 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 14 Web Scripts (+0 failed), 103 URLs
2021-06-25 10:16:48,291 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Package Description Documents (+0 failed) 
2021-06-25 10:16:48,291 INFO [extensions.webscripts.DeclarativeRegistry] [asynchronouslyRefreshedCacheThreadPool1] Registered 0 Schema Description Documents (+0 failed) 
2021-06-25 10:16:48,357 WARN [scripts.servlet.X509ServletFilterBase] [main] clientAuth does not appear to be set for Tomcat. clientAuth must be set to 'want' for X509 Authentication
2021-06-25 10:16:48,357 WARN [scripts.servlet.X509ServletFilterBase] [main] Attempting to set clientAuth=want through JMX...
2021-06-25 10:16:48,361 WARN [scripts.servlet.X509ServletFilterBase] [main] Unable to set clientAuth=want through JMX.
25-Jun-2021 10:16:50.298 INFO [main] org.apache.catalina.startup.HostConfig.deployDescriptor Deployment of deployment descriptor [/etc/opt/alfresco/tomcat/conf/Catalina/localhost/alfresco.xml] has finished in [104,106] ms
25-Jun-2021 10:16:50.298 INFO [main] org.apache.catalina.startup.HostConfig.deployDescriptor Deploying deployment descriptor [/etc/opt/alfresco/tomcat/conf/Catalina/localhost/share.xml]
25-Jun-2021 10:16:50.299 WARNING [main] org.apache.catalina.startup.HostConfig.deployDescriptor Deployment of deployment descriptor [/etc/opt/alfresco/tomcat/conf/Catalina/localhost/share.xml] with an external docBase means the directory [/var/opt/alfresco/tomcat/webapps/share] in the appBase will be ignored
25-Jun-2021 10:16:50.715 WARNING [main] org.apache.catalina.webresources.Cache.getResource Unable to add the resource at [/WEB-INF/classes/alfresco/messages/slingshot_nl.properties] to the cache for web application [/share] because there was insufficient free space available after evicting expired cache entries - consider increasing the maximum size of the cache
25-Jun-2021 10:16:50.717 WARNING [main] org.apache.catalina.webresources.Cache.getResource Unable to add the resource at [/WEB-INF/classes/alfresco/messages/common_nb.properties] to the cache for web application [/share] because there was insufficient free space available after evicting expired cache entries - consider increasing the maximum size of the cache
225-Jun-2021 10:16:50.768 WARNING [main] org.apache.catalina.webresources.Cache.getResource Unable to add the resource at [/WEB-INF/classes/org/springframework/extensions/surf] to the cache for web application [/share] because there was insufficient free space available after evicting expired cache entries - consider increasing the maximum size of the cache
25-Jun-2021 10:16:50.768 WARNING [main] org.apache.catalina.webresources.Cache.getResource Unable to add the resource at [/WEB-INF/classes/org/springframework/extensions/surf/bootstrap] to the cache for web application [/share] because there was insufficient free space available after evicting expired cache entries - consider increasing the maximum size of the cache
25-Jun-2021 10:16:50.768 WARNING [main] org.apache.catalina.webresources.Cache.getResource Unable to add the resource at [/WEB-INF/classes/org/springframework/extensions/surf/forms-client-context.xml] to the cache for web application [/share] because there was insufficient free space available after evicting expired cache entries - consider increasing the maximum size of the cache
25-Jun-2021 10:16:50.769 WARNING [main] org.apache.catalina.webresources.Cache.getResource Unable to add the resource at [/WEB-INF/classes/org/springframework/extensions/surf/bootstrap/forms-bootstrap-context.xml] to the cache for web application [/share] because there was insufficient free space available after evicting expired cache entries - consider increasing the maximum size of the cache
25-Jun-2021 10:16:54.179 WARNING [main] org.apache.catalina.webresources.Cache.getResource Unable to add the resource at [/WEB-INF/classes/] to the cache for web application [/share] because there was insufficient free space available after evicting expired cache entries - consider increasing the maximum size of the cache
25-Jun-2021 10:16:54.186 WARNING [main] org.apache.catalina.webresources.Cache.getResource Unable to add the resource at [/WEB-INF/classes/] to the cache for web application [/share] because there was insufficient free space available after evicting expired cache entries - consider increasing the maximum size of the cache
25-Jun-2021 10:16:54.198 WARNING [main] org.apache.catalina.webresources.Cache.getResource Unable to add the resource at [/WEB-INF/classes/] to the cache for web application [/share] because there was insufficient free space available after evicting expired cache entries - consider increasing the maximum size of the cache
25-Jun-2021 10:16:54.199 WARNING [main] org.apache.catalina.webresources.Cache.getResource Unable to add the resource at [/WEB-INF/classes/] to the cache for web application [/share] because there was insufficient free space available after evicting expired cache entries - consider increasing the maximum size of the cache
25-Jun-2021 10:16:54.296 INFO [main] org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
25-Jun-2021 10:16:54.309 WARNING [main] org.apache.catalina.webresources.Cache.getResource Unable to add the resource at [/WEB-INF/classes/spring.properties] to the cache for web application [/share] because there was insufficient free space available after evicting expired cache entries - consider increasing the maximum size of the cache
25-Jun-2021 10:16:54.310 WARNING [main] org.apache.catalina.webresources.Cache.getResource Unable to add the resource at [/WEB-INF/classes/org/springframework/web/context/ContextLoader.properties] to the cache for web application [/share] because there was insufficient free space available after evicting expired cache entries - consider increasing the maximum size of the cache
25 ....

[michaelzietlow · Accepted solution]

Figured out a solution that works for me!
I had to specify the cacheMaxSize inside the share.xml.

See "AFTER":

BEFORE: /etc/opt/alfresco/tomcat/conf/Catalina/localhost/share.xml

<?xml version='1.0' encoding='utf-8'?>
<Context crossContext="true" docBase="/opt/alfresco/content-services-7.0.0/web-server/webapps/share.war">
  <Resources>
<PostResources base="/opt/alfresco/modules/acs-share"
                   className="org.apache.catalina.webresources.DirResourceSet"
                   webAppMount="/WEB-INF/lib"/>
  </Resources>
</Context>

AFTER:  /etc/opt/alfresco/tomcat/conf/Catalina/localhost/share.xml

<?xml version='1.0' encoding='utf-8'?>
<Context crossContext="true" docBase="/opt/alfresco/content-services-7.0.0/web-server/webapps/share.war">
  <Resources cachingAllowed="true" cacheMaxSize="300000">
<PostResources base="/opt/alfresco/modules/acs-share"
                   className="org.apache.catalina.webresources.DirResourceSet"
                   webAppMount="/WEB-INF/lib"/>
  </Resources>
</Context>

[EddieMay]

Hi @michaelzietlow 

Great you got it sorted and thanks for showing how you did it - really useful to other users.

Cheers,

[Riddell]

For completeness, as a docker-compose user, the file to be modified inside the share container is:

/usr/local/tomcat/conf/context.xml

I used the following Dockerfile to add the customised file:

FROM alfresco/alfresco-share:7.1.0.1

ARG TOMCAT_DIR=/usr/local/tomcat

COPY assets/context.xml ${TOMCAT_DIR}/conf/

Where my context.xml file had the following Resources attribute configured.

<!-- Increase static resource cache size to 50MB to prevent Tomcat cache warnings at startup -->
<Resources cacheMaxSize="51200" />

[abhinavmishra14]

@Riddell  You can modify the existing conext file so you don't have to override it by copying a context file.

#Disable resource caching
RUN sed -i "s|<Context>|<Context>\n<Resources cachingAllowed=\"false\"/> |g" $TOMCAT_DIR/conf/context.xml ;

or

#Increase resource caching size
RUN sed -i "s|<Context>|<Context>\n<Resources cacheMaxSize=\"51200\"/> |g" $TOMCAT_DIR/conf/context.xml ;

Example: https://github.com/abhinavmishra14/change-acs-share-port-demo/blob/master/configs-to-override/alfres... 

https://github.com/abhinavmishra14/change-acs-share-port-demo/blob/master/configs-to-override/share/...