cancel
Showing results for 
Search instead for 
Did you mean: 

Issues seen post 7.1 to 7.4 data/content migration.

michaelzietlow
Confirmed Champ
Confirmed Champ

Hi,

I've installed Alfresco 7.4 with ansible (installer v2.0) and migrated my database and content store to it.  All services are running, I can a...

index.subsystem.name=solr6
solr.host=solr6 solr.port=8983 solr.secureComms=secret solr.sharedSecret=secret

&

Recommended settings for solrcore.properties (both cores: alfresco and archive)

alfresco.host=alfresco
alfresco.port=8080
alfresco.secureComms=secret
alfresco.secureComms.secret=secret

As you can see here communications between Alfresco and Solr were configured to use "secret" but nothing can be changed, only viewed:

EXAMPLE:
I Select "Like"

michaelzietlow_0-1738356741529.jpeg

I see Failed to save to favorite pop-up:

michaelzietlow_1-1738356783544.jpeg

Tailing /var/log/alfresco/catalina.out durring startup shows a doclist error when I 1st login. But says nothing else when the Failed message appears...

 

 

 

 

 

[root@alfresco-test /var/log/alfresco]# tail -f catalina.out 
31-Jan-2025 12:20:43.123 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.io.tmpdir=/var/opt/alfresco/tomcat/temp
31-Jan-2025 12:20:43.124 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent The Apache Tomcat Native library which allows using OpenSSL was not found on the java.library.path: [/usr/java/packages/lib:/usr/lib64:/lib64:/lib:/usr/lib]
31-Jan-2025 12:20:43.335 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-8080"]
31-Jan-2025 12:20:43.360 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["https-jsse-nio-8443"]
31-Jan-2025 12:20:43.774 INFO [main] org.apache.tomcat.util.net.AbstractEndpoint.logCertificate Connector [https-jsse-nio-8443], TLS virtual host [_default_], certificate type [RSA] configured from keystore [/opt/keystore/keystore.jks] using alias [tomcat] with trust store [null]
31-Jan-2025 12:20:43.778 INFO [main] org.apache.catalina.startup.Catalina.load Server initialization in [812] milliseconds
31-Jan-2025 12:20:43.831 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Catalina]
31-Jan-2025 12:20:43.831 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/10.1.33]
...
2025-01-31T12:21:32,335 [] INFO  [alfresco.repo.admin] [http-nio-8080-exec-8] Using database URL 'jdbc:postgresql://127.0.0.1:5432/alfresco' with user 'alfresco'.
2025-01-31T12:21:32,335 [] INFO  [alfresco.repo.admin] [http-nio-8080-exec-8] Connected to database PostgreSQL version 15.10
2025-01-31T12:21:32,335 [] INFO  [api.probes.ProbeEntityResource] [http-nio-8080-exec-8] readyProbe: Success - Tested
2025-01-31T12:21:33,360 [] ERROR [extensions.surf.ClosureJavaScriptCompressionHandler] [https-jsse-nio-8443-exec-6] input.js:101: ERROR - Parse error. Unterminated string literal
2025-01-31T12:21:34,541 [] ERROR [extensions.surf.ClosureJavaScriptCompressionHandler] [https-jsse-nio-8443-exec-6] input.js:101: ERROR - Parse error. Unterminated string literal
2025-01-31T12:21:35,170 [] INFO  [web.scripts.ImapServerStatus] [https-jsse-nio-8443-exec-6] Successfully retrieved IMAP server status from Alfresco: disabled
2025-01-31T12:21:39,641 [] INFO  [management.subsystems.ChildApplicationContextFactory] [http-nio-8080-exec-5] Starting 'Search' subsystem, ID: [Search, managed, solr6]
2025-01-31T12:21:39,732 [] INFO  [management.subsystems.ChildApplicationContextFactory] [http-nio-8080-exec-5] Startup of 'Search' subsystem, ID: [Search, managed, solr6] complete
2025-01-31T12:21:40,023 [] ERROR [extensions.webscripts.AbstractRuntime] [http-nio-8080-exec-5] Exception from executeScript: 00310003 Wrapped Exception (with status template): 00310054 Failed to execute script 'classpath*:alfresco/templates/webscripts/org/alfresco/slingshot/documentlibrary/doclist.get.js': 00310053 Failed to execute search:  +@cm\:modified:[2025\-1\-24T00\:00\:00.000 TO 2025\-1\-31T23\:59\:59.999] +@cm\:modifier:"test_user" +TYPE:"cm:content" -TYPE:"cm:systemfolder" -TYPE:"fm:forums" -TYPE:"fm:forum" -TYPE:"fm:topic" -TYPE:"fm:post" +(TYPE:"content" OR TYPE:"app:filelink" OR TYPE:"folder")
org.springframework.extensions.webscripts.WebScriptException: 00310003 Wrapped Exception (with status template): 00310054 Failed to execute script 'classpath*:alfresco/templates/webscripts/org/alfresco/slingshot/documentlibrary/doclist.get.js': 00310053 Failed to execute search:  +@cm\:modified:[2025\-1\-24T00\:00\:00.000 TO 2025\-1\-31T23\:59\:59.999] +@cm\:modifier:"test_user" +TYPE:"cm:content" -TYPE:"cm:systemfolder" -TYPE:"fm:forums" -TYPE:"fm:forum" -TYPE:"fm:topic" -TYPE:"fm:post" +(TYPE:"content" OR TYPE:"app:filelink" OR TYPE:"folder")
	at org.springframework.extensions.webscripts.AbstractWebScript.createStatusException(AbstractWebScript.java:1139) ~[spring-webscripts-9.4.jar:9.4]
	at org.springframework.extensions.webscripts.DeclarativeWebScript.execute(DeclarativeWebScript.java:171) ~[spring-webscripts-9.4.jar:9.4]
	at org.alfresco.repo.web.scripts.RepositoryContainer.lambda$transactionedExecute$2(RepositoryContainer.java:553) ~[alfresco-remote-api-23.4.0.67.jar:23.4.0.67]
	at org.alfresco.repo.transaction.RetryingTransactionHelper.doInTransaction(RetryingTransactionHelper.java:452) ~[alfresco-repository-23.4.0.67.jar:23.4.0.67]
	at org.alfresco.repo.web.scripts.RepositoryContainer.transactionedExecute(RepositoryContainer.java:544) ~[alfresco-remote-api-23.4.0.67.jar:23.4.0.67]
	at org.alfresco.repo.web.scripts.RepositoryContainer.transactionedExecuteAs(RepositoryContainer.java:685) ~[alfresco-remote-api-23.4.0.67.jar:23.4.0.67]
	at org.alfresco.repo.web.scripts.RepositoryContainer.transactionedExecuteAs(RepositoryContainer.java:721) ~[alfresco-remote-api-23.4.0.67.jar:23.4.0.67]
	at org.alfresco.repo.web.scripts.RepositoryContainer.executeScriptInternal(RepositoryContainer.java:413) ~[alfresco-remote-api-23.4.0.67.jar:23.4.0.67]
	at org.alfresco.repo.web.scripts.RepositoryContainer.executeScript(RepositoryContainer.java:309) ~[alfresco-remote-api-23.4.0.67.jar:23.4.0.67]
	at org.springframework.extensions.webscripts.AbstractRuntime.executeScript(AbstractRuntime.java:423) [spring-webscripts-9.4.jar:9.4]
	at org.springframework.extensions.webscripts.AbstractRuntime.executeScript(AbstractRuntime.java:210) [spring-webscripts-9.4.jar:9.4]
	at org.springframework.extensions.webscripts.servlet.WebScriptServlet.service(WebScriptServlet.java:131) [spring-webscripts-9.4.jar:9.4]
	at org.alfresco.repo.web.scripts.AlfrescoWebScriptServlet.service(AlfrescoWebScriptServlet.java:43) [alfresco-remote-api-23.4.0.67.jar:23.4.0.67]
	at jakarta.servlet.http.HttpServlet.service(HttpServlet.java:658) [servlet-api.jar:6.0]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:195) [catalina.jar:10.1.33]
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:140) [catalina.jar:10.1.33]
	at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:51) [tomcat-websocket.jar:10.1.33]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:164) [catalina.jar:10.1.33]
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:140) [catalina.jar:10.1.33]
	at org.alfresco.module.aosmodule.service.ContextRootFilter.doFilter(ContextRootFilter.java:93) [alfresco-aos-repo-binding-3.2.0.jar:?]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:164) [catalina.jar:10.1.33]
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:140) [catalina.jar:10.1.33]
	at org.springframework.extensions.webscripts.servlet.SecurityHeadersFilter.doFilter(SecurityHeadersFilter.java:177) [spring-webscripts-9.4.jar:9.4]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:164) [catalina.jar:10.1.33]
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:140) [catalina.jar:10.1.33]
	at org.alfresco.web.app.servlet.GlobalLocalizationFilter.doFilter(GlobalLocalizationFilter.java:68) [alfresco-remote-api-23.4.0.67.jar:23.4.0.67]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:164) [catalina.jar:10.1.33]
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:140) [catalina.jar:10.1.33]
	at org.alfresco.web.app.servlet.ClearSecurityContextFilter.doFilter(ClearSecurityContextFilter.java:53) [alfresco-remote-api-23.4.0.67.jar:23.4.0.67]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:164) [catalina.jar:10.1.33]
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:140) [catalina.jar:10.1.33]
	at org.apache.catalina.filters.CorsFilter.handleNonCORS(CorsFilter.java:331) [catalina.jar:10.1.33]
	at org.apache.catalina.filters.CorsFilter.doFilter(CorsFilter.java:158) [catalina.jar:10.1.33]
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:164) [catalina.jar:10.1.33]
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:140) [catalina.jar:10.1.33]
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:167) [catalina.jar:10.1.33]
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:90) [catalina.jar:10.1.33]
	at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:483) [catalina.jar:10.1.33]
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:115) [catalina.jar:10.1.33]
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:93) [catalina.jar:10.1.33]
	at org.apache.catalina.valves.RemoteIpValve.invoke(RemoteIpValve.java:731) [catalina.jar:10.1.33]
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:74) [catalina.jar:10.1.33]
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:344) [catalina.jar:10.1.33]
	at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:397) [tomcat-coyote.jar:10.1.33]
	at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:63) [tomcat-coyote.jar:10.1.33]
	at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:905) [tomcat-coyote.jar:10.1.33]
	at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1741) [tomcat-coyote.jar:10.1.33]
	at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:52) [tomcat-coyote.jar:10.1.33]
	at org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1190) [tomcat-util.jar:10.1.33]
	at org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659) [tomcat-util.jar:10.1.33]
	at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:63) [tomcat-util.jar:10.1.33]
	at java.base/java.lang.Thread.run(Thread.java:840) [?:?]
2025-01-31T12:37:08,162 [] INFO  [alfresco.repo.admin] [http-nio-8080-exec-9] Using database URL 'jdbc:postgresql://127.0.0.1:5432/alfresco' with user 'alfresco'.
2025-01-31T12:37:08,163 [] INFO  [alfresco.repo.admin] [http-nio-8080-exec-9] Connected to database PostgreSQL version 15.10
2025-01-31T12:37:08,163 [] INFO  [api.probes.ProbeEntityResource] [http-nio-8080-exec-9] readyProbe: Success - Tested
2025-01-31T12:37:08,376 [] INFO  [web.scripts.QuickShareStatus] [https-jsse-nio-8443-exec-4] Successfully retrieved quick share information from Alfresco.
2025-01-31T12:37:08,415 [] INFO  [management.subsystems.ChildApplicationContextFactory] [http-nio-8080-exec-4] Starting 'Transformers' subsystem, ID: [Transformers, default]
2025-01-31T12:37:08,423 [] INFO  [management.subsystems.ChildApplicationContextFactory] [http-nio-8080-exec-4] Startup of 'Transformers' subsystem, ID: [Transformers, default] complete
2025-01-31T12:37:12,444 [] INFO  [web.scripts.MimetypesQuery] [https-jsse-nio-8443-exec-3] Successfully retrieved mimetypes information from Alfresco.
2025-01-31T12:37:28,196 [] INFO  [alfresco.repo.admin] [http-nio-8080-exec-9] Using database URL 'jdbc:postgresql://127.0.0.1:5432/alfresco' with user 'alfresco'.
2025-01-31T12:37:28,196 [] INFO  [alfresco.repo.admin] [http-nio-8080-exec-9] Connected to database PostgreSQL version 15.10
2025-01-31T12:37:28,196 [] INFO  [api.probes.ProbeEntityResource] [http-nio-8080-exec-9] readyProbe: Success - Tested
2025-01-31T12:37:48,228 [] INFO  [alfresco.repo.admin] [http-nio-8080-exec-5] Using database URL 'jdbc:postgresql://127.0.0.1:5432/alfresco' with user 'alfresco'.
2025-01-31T12:37:48,228 [] INFO  [alfresco.repo.admin] [http-nio-8080-exec-5] Connected to database PostgreSQL version 15.10
2025-01-31T12:37:48,228 [] INFO  [api.probes.ProbeEntityResource] [http-nio-8080-exec-5] readyProbe: Success - Teste

 

 

 

 

 

The only log I see populating during the failure is the nginx log which is just coincidental:

 

 

 

 

 

10.1.1.2 - - [30/Jan/2025:16:29:49 -0800] "GET /share/proxy/alfresco/api/people/admin_user/preferences?pf=org.alfresco.share.documents.favourites HTTP/1.1" 200 130 "https://alfresco1.dmz.moesol.com/share/page/site/it/document-details?nodeRef=workspace://SpacesStore/f23605fc-a675-4d3d-8a35-eedf49489d0c" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36"
10.1.1.2 - - [30/Jan/2025:16:29:49 -0800] "POST /share/proxy/alfresco/api/people/admin_user/preferences HTTP/1.1" 403 0 "https://alfresco1.dmz.moesol.com/share/page/site/it/document-details?nodeRef=workspace://SpacesStore/f23605fc-a675-4d3d-8a35-eedf49489d0c" "Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36"
10.1.1.20 - - [30/Jan/2025:16:29:50 -0800] "GET /alfresco/service/api/solr/aclchangesets?fromId=0&toId=2000&maxResults=1 HTTP/1.1" 400 73 "-" "Jakarta Commons-HttpClient/3.1"
10.1.1.20 - - [30/Jan/2025:16:29:50 -0800] "GET /alfresco/service/api/solr/transactions?fromCommitTime=0&toCommitTime=0&baseUrl=%2Fsolr%2Falfresco&hostName=localhost&template=rerank&state.property.coreName=alfresco&instance=0&numberOfShards=1&port=8983&stores=workspace%3A%2F%2FSpacesStore&isMaster=true&hasContent=true&shardMethod=DB_ID&lastUpdated=1738283390001&lastIndexedChangeSetCommitTime=1738274028727&lastIndexedChangeSetId=3028&lastIndexedTxCommitTime=1738274028746&lastIndexedTxId=239104 HTTP/1.1" 400 73 "-" "Jakarta Commons-HttpClient/3.1"
10.1.1.20 - - [30/Jan/2025:16:29:50 -0800] "GET /alfresco/service/api/solr/aclchangesets?fromId=0&toId=2000&maxResults=1 HTTP/1.1" 400 73 "-" "Jakarta Commons-HttpClient/3.1"
10.1.1.20 - - [30/Jan/2025:16:29:50 -0800] "GET /alfresco/service/api/solr/transactions?fromCommitTime=0&toCommitTime=0&baseUrl=%2Fsolr%2Farchive&hostName=localhost&template=rerank&state.property.coreName=archive&instance=0&numberOfShards=1&port=8983&stores=archive%3A%2F%2FSpacesStore&isMaster=true&hasContent=true&shardMethod=DB_ID&lastUpdated=1738283390001&lastIndexedChangeSetCommitTime=1738274028727&lastIndexedChangeSetId=3028&lastIndexedTxCommitTime=1738274028746&lastIndexedTxId=239104 HTTP/1.1" 400 73 "-" "Jakarta Commons-HttpClient/3.1"
10.1.1.20 - - [30/Jan/2025:16:29:50 -0800] "POST /alfresco/service/api/solr/modelsdiff HTTP/1.1" 400 98 "-" "Jakarta Commons-HttpClient/3.1"
 

 

 

 

 

 

 

 

 

 

 

1 REPLY 1

michaelzietlow
Confirmed Champ
Confirmed Champ

Seeing errors like this in solr.log:

2025-02-01 00:30:42.034 ERROR (searcherExecutor-7-thread-1-processing-x:archive) [   x:archive] o.a.s.t.ModelTracker Model tracking failed for core: archive
org.alfresco.error.AlfrescoRuntimeException: 01010000 api/solr/modelsdiff return status:400
at org.alfresco.solr.client.SOLRAPIClient.callRepository(SOLRAPIClient.java:1598)
at org.alfresco.solr.client.SOLRAPIClient.getModelsDiff(SOLRAPIClient.java:1105)
at org.alfresco.solr.tracker.ModelTracker.trackModelsImpl(ModelTracker.java:313)
at org.alfresco.solr.tracker.ModelTracker.trackModels(ModelTracker.java:275)
at org.alfresco.solr.tracker.ModelTracker.ensureFirstModelSync(ModelTracker.java:297)
at org.alfresco.solr.lifecycle.SolrCoreLoadListener.createModelTracker(SolrCoreLoadListener.java:345)
at org.alfresco.solr.lifecycle.SolrCoreLoadListener.newSearcher(SolrCoreLoadListener.java:139)
at org.apache.solr.core.SolrCore.lambda$getSearcher$15(SolrCore.java:2249)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
at java.base/java.lang.Thread.run(Thread.java:840)
2025-02-01 00:30:42.055 WARN  (searcherExecutor-8-thread-1-processing-x:alfresco) [   x:alfresco] o.a.s.t.MetadataTracker nextTxCommitTimeService is not available. Upgrade your ACS Repository version in order to use this feature: alfresco - GetNextTxCommitTime return status is 400 when invoking api/solr/nextTransaction?fromCommitTime=0 
2025-02-01 00:30:42.055 WARN  (searcherExecutor-7-thread-1-processing-x:archive) [   x:archive] o.a.s.t.MetadataTracker nextTxCommitTimeService is not available. Upgrade your ACS Repository version in order to use this feature: archive - GetNextTxCommitTime return status is 400 when invoking api/solr/nextTransaction?fromCommitTime=0 
2025-02-01 00:30:42.063 ERROR (searcherExecutor-7-thread-1-processing-x:archive) [   x:archive] o.a.s.c.SolrCore null:java.lang.NoClassDefFoundError: org/springframework/transaction/support/TransactionSynchronization
at org.alfresco.util.cache.AbstractAsynchronouslyRefreshedCache.onRefreshableCacheEvent(AbstractAsynchronouslyRefreshedCache.java:253)
at org.alfresco.util.cache.DefaultAsynchronouslyRefreshedCacheRegistry.broadcastEvent(DefaultAsynchronouslyRefreshedCacheRegistry.java:58)
at org.alfresco.util.cache.AbstractAsynchronouslyRefreshedCache.refresh(AbstractAsynchronouslyRefreshedCache.java:235)
at org.alfresco.solr.component.AsyncBuildSuggestComponent$SuggesterListener.newSearcher(AsyncBuildSuggestComponent.java:589)
at org.apache.solr.core.SolrCore.lambda$getSearcher$15(SolrCore.java:2249)
at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229)
at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
at java.base/java.lang.Thread.run(Thread.java:840)
Caused by: java.lang.ClassNotFoundException: org.springframework.transaction.support.TransactionSynchronization
at java.base/java.net.URLClassLoader.findClass(URLClassLoader.java:445)
at java.base/java.lang.ClassLoader.loadClass(ClassLoader.java:592)
at java.base/java.lang.ClassLoader.loadClass(ClassLoader.java:525)
at org.eclipse.jetty.webapp.WebAppClassLoader.loadClass(WebAppClassLoader.java:538)
at java.base/java.lang.ClassLoader.loadClass(ClassLoader.java:525)
... 10 more