[ https://issues.apache.org/jira/browse/SOLR-7899?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=14662753#comment-14662753 ]
Joel Bernstein commented on SOLR-7899: -------------------------------------- Another failure with a recovery error happening near by: 55 WARN (RecoveryThread-collection1) [n:127.0.0.1:55811_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.h.IndexFetcher Could not retrieve checksum from file. [junit4] 2> java.lang.ArrayIndexOutOfBoundsException [junit4] 2> 208855 WARN (RecoveryThread-collection1) [n:127.0.0.1:55811_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.h.IndexFetcher File _3.fdx did not match. expected length is 84 and actual length is 0 [junit4] 2> 208858 INFO (qtp1682481233-1058) [n:127.0.0.1:55795_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@3e480f7e[collection1] main [junit4] 2> 208859 INFO (qtp1682481233-1058) [n:127.0.0.1:55795_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler end_commit_flush [junit4] 2> 208859 INFO (searcherExecutor-527-thread-1-processing-n:127.0.0.1:55795_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55795_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3e480f7e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.0.0):C8)))} [junit4] 2> 208859 INFO (qtp1682481233-1058) [n:127.0.0.1:55795_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 21 [junit4] 2> 208860 INFO (qtp794519470-1098) [n:127.0.0.1:55803_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/replication params={generation=8&qt=/replication&file=_3.fdx&checksum=true&wt=filestream&command=filecontent} status=0 QTime=2 [junit4] 2> 208867 INFO (qtp794519470-1097) [n:127.0.0.1:55803_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/replication params={generation=8&qt=/replication&file=_3_BlockTreeOrds_0.tipo&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 208868 INFO (qtp1780139914-1127) [n:127.0.0.1:55807_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 208869 INFO (qtp423014036-1155) [n:127.0.0.1:55811_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: BUFFERING replay:0 [junit4] 2> 208870 INFO (qtp423014036-1155) [n:127.0.0.1:55811_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:55807/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false} {commit=} 0 0 [junit4] 2> 208870 INFO (qtp794519470-1099) [n:127.0.0.1:55803_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 208868 INFO (qtp1388903139-1185) [n:127.0.0.1:55816_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 208875 INFO (qtp794519470-1100) [n:127.0.0.1:55803_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/replication params={generation=8&qt=/replication&file=_3_LuceneVarGapFixedInterval_0.tiv&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 208889 INFO (qtp1388903139-1185) [n:127.0.0.1:55816_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4fb8b42 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@43198aee),segFN=segments_7,generation=7} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4fb8b42 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@43198aee),segFN=segments_8,generation=8} [junit4] 2> 208889 INFO (qtp1388903139-1185) [n:127.0.0.1:55816_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.SolrCore newest commit generation = 8 [junit4] 2> 208891 INFO (qtp1388903139-1185) [n:127.0.0.1:55816_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@7b47ee03[collection1] main [junit4] 2> 208892 INFO (qtp1388903139-1185) [n:127.0.0.1:55816_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.UpdateHandler end_commit_flush [junit4] 2> 208892 INFO (searcherExecutor-567-thread-1-processing-n:127.0.0.1:55816_ x:collection1 s:shard1 c:collection1 r:core_node4) [n:127.0.0.1:55816_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7b47ee03[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.0.0):C3)))} [junit4] 2> 208892 INFO (qtp1388903139-1185) [n:127.0.0.1:55816_ c:collection1 s:shard1 r:core_node4 x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:55807/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false} {commit=} 0 24 [junit4] 2> 208898 INFO (qtp1780139914-1127) [n:127.0.0.1:55807_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@317b956e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@dbbdb05),segFN=segments_7,generation=7} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@317b956e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@dbbdb05),segFN=segments_8,generation=8} [junit4] 2> 208899 INFO (qtp1780139914-1127) [n:127.0.0.1:55807_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore newest commit generation = 8 [junit4] 2> 208901 INFO (qtp1780139914-1127) [n:127.0.0.1:55807_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@4fb482f0[collection1] main [junit4] 2> 208902 INFO (searcherExecutor-547-thread-1-processing-n:127.0.0.1:55807_ x:collection1 s:shard1 c:collection1 r:core_node2) [n:127.0.0.1:55807_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4fb482f0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.0.0):C3)))} [junit4] 2> 208903 INFO (qtp1780139914-1127) [n:127.0.0.1:55807_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.UpdateHandler end_commit_flush [junit4] 2> 208903 INFO (qtp1780139914-1127) [n:127.0.0.1:55807_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:55807/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false} {commit=} 0 35 [junit4] 2> 208905 INFO (qtp794519470-1099) [n:127.0.0.1:55803_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@19bbebf7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1b325aa5),segFN=segments_8,generation=8} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@19bbebf7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1b325aa5),segFN=segments_9,generation=9} [junit4] 2> 208905 INFO (qtp794519470-1099) [n:127.0.0.1:55803_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 9 [junit4] 2> 208905 INFO (qtp794519470-1099) [n:127.0.0.1:55803_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@297a55ee[collection1] main [junit4] 2> 208906 INFO (searcherExecutor-537-thread-1-processing-n:127.0.0.1:55803_ x:collection1 s:shard2 c:collection1 r:core_node1) [n:127.0.0.1:55803_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@297a55ee[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(6.0.0):C4) Uninverting(_4(6.0.0):C1)))} [junit4] 2> 208907 INFO (qtp794519470-1099) [n:127.0.0.1:55803_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.UpdateHandler end_commit_flush [junit4] 2> 208907 INFO (qtp794519470-1099) [n:127.0.0.1:55803_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:55807/collection1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false} {commit=} 0 37 [junit4] 2> 208908 INFO (qtp794519470-1095) [n:127.0.0.1:55803_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/replication params={generation=8&qt=/replication&file=_3.si&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 208925 INFO (qtp1780139914-1128) [n:127.0.0.1:55807_ c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp= path=/update params={_stateVer_=collection1:12&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} {commit=} 0 59 [junit4] 2> 208930 INFO (qtp794519470-1098) [n:127.0.0.1:55803_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/replication params={generation=8&qt=/replication&file=_3_Lucene50_0.dvd&checksum=true&wt=filestream&command=filecontent} status=0 QTime=0 [junit4] 2> 208931 INFO (qtp794519470-1096) [n:127.0.0.1:55803_ c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/sql params={wt=json&version=2.2&sql=select+year_i,+sum(item_i)+from+collection1+group+by+year_i+order+by+year_i+desc} status=0 QTime=3 [junit4] 2> 208936 INFO (qtp423014036-1156) [n:127.0.0.1:55811_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/export params={q=*:*&distrib=false&fl=year_i,item_i&sort=year_i+desc&wt=json&version=2.2} hits=0 status=0 QTime=0 [junit4] 2> 208937 ERROR (qtp423014036-1156) [n:127.0.0.1:55811_ c:collection1 s:shard2 r:core_node3 x:collection1] o.a.s.s.SolrDispatchFilter null:java.lang.NullPointerException [junit4] 2> at org.apache.solr.response.SortingResponseWriter.write(SortingResponseWriter.java:107) [junit4] 2> at org.apache.solr.response.QueryResponseWriterUtil.writeQueryResponse(QueryResponseWriterUtil.java:53) [junit4] 2> at org.apache.solr.servl > Non-reproducible test failures on TestSQLHandler > ------------------------------------------------ > > Key: SOLR-7899 > URL: https://issues.apache.org/jira/browse/SOLR-7899 > Project: Solr > Issue Type: Bug > Reporter: Joel Bernstein > > This ticket is to explore the NPE below that appears about once a week on > Jenkins during the TestSQLHandler.doTest testcase. > null:java.lang.NullPointerException > [junit4] 2> at > org.apache.solr.response.SortingResponseWriter.write(SortingResponseWriter.java:107) > [junit4] 2> at > org.apache.solr.response.QueryResponseWriterUtil.writeQueryResponse(QueryResponseWriterUtil.java:53) > [junit4] 2> at > org.apache.solr.servlet.HttpSolrCall.writeResponse(HttpSolrCall.java:722) > [junit4] 2> at > org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:462) > [junit4] 2> at > org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:226) > [junit4] 2> at > org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:195) > [junit4] 2> at > org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) > [junit4] 2> at > org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:106) > [junit4] 2> at > org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) > [junit4] 2> at > org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83) > [junit4] 2> at > org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:300) > [junit4] 2> at > org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) > [junit4] 2> at > org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585) > [junit4] 2> at > org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221) > [junit4] 2> at > org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127) > [junit4] 2> at > org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515) > [junit4] 2> at > org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) > [junit4] 2> at > org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061) > [junit4] 2> at > org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) > [junit4] 2> at > org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97) > [junit4] 2> at > org.eclipse.jetty.server.Server.handle(Server.java:499) > [junit4] 2> at > org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310) > [junit4] 2> at > org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257) > [junit4] 2> at > org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540) > [junit4] 2> at > org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635) > [junit4] 2> at > org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555) > [junit4] 2> at java.lang.Thread.run(Thread.java:745) > [junit4] 2> -- This message was sent by Atlassian JIRA (v6.3.4#6332) --------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org