You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@solr.apache.org by "Mike Drob (Jira)" <ji...@apache.org> on 2022/08/24 15:52:00 UTC

[jira] [Created] (SOLR-16356) DBQ is noisy during core close

Mike Drob created SOLR-16356:
--------------------------------

             Summary: DBQ is noisy during core close
                 Key: SOLR-16356
                 URL: https://issues.apache.org/jira/browse/SOLR-16356
             Project: Solr
          Issue Type: Bug
            Reporter: Mike Drob


This is unrelated to the cause of the test failure, but shows up in the logs. If we have a DBQ running and the core closes then we get lots of scary stack traces in the logs.

At the point of the stack trace, I believe the DBQ has already been recorded in the update log, we just need to open our realtime searcher so that RTG has the right info. But if we are closing the core then we don't really care about failure to open a new searcher.

{noformat}
520270 INFO  (SUITE-DocExpirationUpdateProcessorFactoryTest-seed#[2BB0ACD37A038A84]-worker) [] o.a.s.SolrTestCaseJ4 ###deleteCore
520270 INFO  (SUITE-DocExpirationUpdateProcessorFactoryTest-seed#[2BB0ACD37A038A84]-worker) [] o.a.s.c.CoreContainer Shutting down CoreContainer instance=736270622
520271 INFO  (coreCloseExecutor-3583-thread-1) [ x:collection1] o.a.s.c.SolrCore CLOSING SolrCore org.apache.solr.core.SolrCore@12243a95 collection1
520271 INFO  (coreCloseExecutor-3583-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@12243a95
520277 INFO  (coreCloseExecutor-3583-thread-1) [ x:collection1] o.a.s.u.p.DocExpirationUpdateProcessorFactory Triggering Graceful close of DocExpiration Executor
520277 INFO  (coreCloseExecutor-3583-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  ... SKIPPED (unnecessary).
522932 ERROR (autoExpireDocs-3581-thread-1) [] o.a.s.u.UpdateLog Error opening realtime searcher
          => org.apache.solr.update.SolrCoreState$CoreIsClosedException
	at org.apache.solr.core.SolrCore.openNewSearcher(SolrCore.java:2268)
org.apache.solr.update.SolrCoreState$CoreIsClosedException: null
	at org.apache.solr.core.SolrCore.openNewSearcher(SolrCore.java:2268) ~[main/:?]
	at org.apache.solr.update.UpdateLog.openRealtimeSearcher(UpdateLog.java:743) [main/:?]
	at org.apache.solr.update.UpdateLog.deleteByQuery(UpdateLog.java:721) [main/:?]
	at org.apache.solr.update.DirectUpdateHandler2.deleteByQuery(DirectUpdateHandler2.java:610) [main/:?]
	at org.apache.solr.update.processor.RunUpdateProcessorFactory$RunUpdateProcessor.processDelete(RunUpdateProcessorFactory.java:86) [main/:?]
	at org.apache.solr.update.processor.UpdateRequestProcessor.processDelete(UpdateRequestProcessor.java:59) [main/:?]
	at org.apache.solr.update.processor.UpdateRequestProcessor.processDelete(UpdateRequestProcessor.java:59) [main/:?]
	at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalDelete(DistributedUpdateProcessor.java:275) [main/:?]
	at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalDeleteByQuery(DistributedUpdateProcessor.java:1010) [main/:?]
	at org.apache.solr.update.processor.DistributedUpdateProcessor.versionDeleteByQuery(DistributedUpdateProcessor.java:979) [main/:?]
	at org.apache.solr.update.processor.DistributedUpdateProcessor.doDeleteByQuery(DistributedUpdateProcessor.java:937) [main/:?]
	at org.apache.solr.update.processor.DistributedUpdateProcessor.doDeleteByQuery(DistributedUpdateProcessor.java:912) [main/:?]
	at org.apache.solr.update.processor.DistributedUpdateProcessor.processDelete(DistributedUpdateProcessor.java:829) [main/:?]
	at org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processDelete(LogUpdateProcessorFactory.java:134) [main/:?]
	at org.apache.solr.update.processor.UpdateRequestProcessor.processDelete(UpdateRequestProcessor.java:59) [main/:?]
	at org.apache.solr.update.processor.RecordingUpdateProcessorFactory$RecordingUpdateRequestProcessor.processDelete(RecordingUpdateProcessorFactory.java:96) [test/:?]
	at org.apache.solr.update.processor.UpdateRequestProcessor.processDelete(UpdateRequestProcessor.java:59) [main/:?]
	at org.apache.solr.update.processor.DocExpirationUpdateProcessorFactory$DeleteExpiredDocsRunnable.run(DocExpirationUpdateProcessorFactory.java:398) [main/:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
	at java.lang.Thread.run(Thread.java:833) [?:?]
522934 INFO  (autoExpireDocs-3581-thread-1) [] o.a.s.u.p.LogUpdateProcessorFactory {deleteByQuery={!cache=false}eXpField_tdt:[* TO 2022-08-24T08:00:20.233Z] (-1742028690144886784)} 0 7692
522934 ERROR (autoExpireDocs-3581-thread-1) [] o.a.s.u.p.DocExpirationUpdateProcessorFactory Runtime error in periodic deletion of expired docs: 
          => org.apache.solr.common.SolrException: SolrCoreState already closed.
	at org.apache.solr.update.DefaultSolrCoreState.lock(DefaultSolrCoreState.java:185)
org.apache.solr.common.SolrException: SolrCoreState already closed.
	at org.apache.solr.update.DefaultSolrCoreState.lock(DefaultSolrCoreState.java:185) ~[main/:?]
	at org.apache.solr.update.DefaultSolrCoreState.getIndexWriter(DefaultSolrCoreState.java:115) ~[main/:?]
	at org.apache.solr.update.DirectUpdateHandler2.commit(DirectUpdateHandler2.java:729) ~[main/:?]
	at org.apache.solr.update.processor.RunUpdateProcessorFactory$RunUpdateProcessor.processCommit(RunUpdateProcessorFactory.java:100) ~[main/:?]
	at org.apache.solr.update.processor.UpdateRequestProcessor.processCommit(UpdateRequestProcessor.java:67) ~[main/:?]
	at org.apache.solr.update.processor.UpdateRequestProcessor.processCommit(UpdateRequestProcessor.java:67) ~[main/:?]
	at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalCommit(DistributedUpdateProcessor.java:1231) ~[main/:?]
	at org.apache.solr.update.processor.DistributedUpdateProcessor.processCommit(DistributedUpdateProcessor.java:1217) ~[main/:?]
	at org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processCommit(LogUpdateProcessorFactory.java:173) ~[main/:?]
	at org.apache.solr.update.processor.UpdateRequestProcessor.processCommit(UpdateRequestProcessor.java:67) ~[main/:?]
	at org.apache.solr.update.processor.RecordingUpdateProcessorFactory$RecordingUpdateRequestProcessor.processCommit(RecordingUpdateProcessorFactory.java:108) ~[test/:?]
	at org.apache.solr.update.processor.UpdateRequestProcessor.processCommit(UpdateRequestProcessor.java:67) ~[main/:?]
	at org.apache.solr.update.processor.DocExpirationUpdateProcessorFactory$DeleteExpiredDocsRunnable.run(DocExpirationUpdateProcessorFactory.java:405) [main/:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
	at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
	at java.lang.Thread.run(Thread.java:833) [?:?]
{noformat}



--
This message was sent by Atlassian Jira
(v8.20.10#820010)

---------------------------------------------------------------------
To unsubscribe, e-mail: issues-unsubscribe@solr.apache.org
For additional commands, e-mail: issues-help@solr.apache.org