[
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: [email protected]
For additional commands, e-mail: [email protected]
