Build: https://builds.apache.org/job/Lucene-Solr-tests-only-trunk-java7/2925/
1 tests failed.
REGRESSION: org.apache.solr.cloud.RecoveryZkTest.testDistribSearch
Error Message:
Thread threw an uncaught exception, thread: Thread[Lucene Merge Thread #2,6,]
Stack Trace:
java.lang.RuntimeException: Thread threw an uncaught exception, thread:
Thread[Lucene Merge Thread #2,6,]
at
com.carrotsearch.randomizedtesting.RunnerThreadGroup.processUncaught(RunnerThreadGroup.java:96)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:859)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.access$700(RandomizedRunner.java:132)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$3$1.run(RandomizedRunner.java:671)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:697)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:736)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:747)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleReportUncaughtExceptions$1.evaluate(TestRuleReportUncaughtExceptions.java:68)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:38)
at
org.apache.lucene.util.TestRuleIcuHack$1.evaluate(TestRuleIcuHack.java:51)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
org.apache.lucene.util.TestRuleNoInstanceHooksOverrides$1.evaluate(TestRuleNoInstanceHooksOverrides.java:53)
at
org.apache.lucene.util.TestRuleNoStaticHooksShadowing$1.evaluate(TestRuleNoStaticHooksShadowing.java:52)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:36)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSuite(RandomizedRunner.java:605)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.access$400(RandomizedRunner.java:132)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$2.run(RandomizedRunner.java:551)
Caused by: org.apache.lucene.index.MergePolicy$MergeException:
org.apache.lucene.store.AlreadyClosedException: this Directory is closed
at __randomizedtesting.SeedInfo.seed([7FB20F9C95367834]:0)
at
org.apache.lucene.index.ConcurrentMergeScheduler.handleMergeException(ConcurrentMergeScheduler.java:507)
at
org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:480)
Caused by: org.apache.lucene.store.AlreadyClosedException: this Directory is
closed
at org.apache.lucene.store.Directory.ensureOpen(Directory.java:244)
at org.apache.lucene.store.FSDirectory.listAll(FSDirectory.java:241)
at
org.apache.lucene.index.IndexFileDeleter.refresh(IndexFileDeleter.java:320)
at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:3136)
at
org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:382)
at
org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:451)
Build Log:
[...truncated 37339 lines...]
[junit4:junit4] 2> 33192 T1080 C119 P61275 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2>
commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk-java7/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1342651282324/jetty2/index.20120718184152228,segFN=segments_4,generation=4,filenames=[_7i.si,
_7h_10.len, _69_3.len, _7h_6.len, _7g_11.len, _7k_4.len, _7i_10.len,
_69_6.len, _69_4.len, _7l_10.len, _7l_3.len, _69.pst, _7m.inf, _7g_6.len,
_77_10.len, _7k_11.len, _77.inf, _69_1.len, _7l_4.len, _69.inf, _77_11.len,
_7l.fld, _7m.fld, _7k_1.len, _7i_7.len, _7k_7.len, _7g.inf, _7g_3.len, _7k.fld,
_7l.si, _7l_1.len, _7m_4.len, _77_4.len, _7i_8.len, _7j_3.len, _69_8.len,
_7h_7.len, _77_6.len, _7m_6.len, _7g_1.liv, _77_7.len, _7m_10.len, _7h_8.len,
_77_1.liv, _7j.si, _7l_8.len, _7g.fld, _7k.si, _7g_7.len, _7k.pst, _77.fld,
_7j.pst, _77.si, _7k_8.len, _7h.pst, _7m.si, _69_1.liv, _7m_11.len, _7j.fld,
_7i_4.len, _7m_8.len, _7g_4.len, _7j_7.len, _7h.inf, _69_11.len, _7h.si,
_7j_6.len, _7m.pst, _7g.pst, _7k_6.len, _7g_8.len, _7j_8.len, _7j_4.len,
_7j_11.len, _77_3.len, _7j.inf, _7h_3.len, _7h_4.len, _7i.fld, _7h_11.len,
_7i.pst, _69.fld, _7h_1.len, _7i_3.len, _7m_1.len, _77.pst, _7g_10.len,
_7k_10.len, _7g_1.len, _77_8.len, _7j_10.len, _69.si, _7l.pst, _7l_11.len,
_69_7.len, _7k.inf, _7i_11.len, _7m_3.len, _7i_6.len, _69_10.len, _7j_1.len,
_77_1.len, _7h.fld, _7m_7.len, _7l_7.len, _7l_6.len, _7i.inf, _7l.inf, _7g.si,
_7k_3.len, _7i_1.len, segments_4]
[junit4:junit4] 2>
commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk-java7/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1342651282324/jetty2/index.20120718184152228,segFN=segments_5,generation=5,filenames=[_69_3.len,
_7v_3.len, _7w_3.len, _7v_8.len, _69_11.len, _69_6.len, _7v_11.len, _69_4.len,
_7x_10.len, _69.pst, _7v.inf, _7x_11.len, _7w_6.len, _69_1.len, _7v.pst,
_69.inf, _7x_1.len, _7x_8.len, _7w_4.len, _7x_3.len, _7x_4.len, _7w.fld,
_7x.inf, _7v_4.len, _69.fld, _7v_10.len, _7x_6.len, _69_8.len, _7v_7.len,
_7w_11.len, _69.si, _7x.pst, _7v_1.len, _69_7.len, _7x_7.len, _7w_7.len,
_69_10.len, _7w.inf, _7w_1.len, _7x.si, _7w_8.len, _7w.pst, _7v.fld, _7w_1.liv,
_7v_6.len, _69_1.liv, _7w_10.len, _7x.fld, _7v.si, segments_5, _7w.si]
[junit4:junit4] 2> 33193 T1080 C119 P61275
oasc.SolrDeletionPolicy.updateCommits newest commit = 5
[junit4:junit4] 2> 33209 T1080 C119 P61275 oass.SolrIndexSearcher.<init>
Opening Searcher@2697cba5 main
[junit4:junit4] 2> 33210 T1080 C119 P61275 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4:junit4] 2> 33210 T1114 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@2697cba5
main{StandardDirectoryReader(segments_5:609:nrt _69(5.0):C1024/18
_7w(5.0):C261/6 _7v(5.0):C10 _7x(5.0):C3)}
[junit4:junit4] 2> 33212 T1080 C119 P61275 /update
{waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2}
{commit=} 0 122
[junit4:junit4] 2> 33212 T999 C118 P37833 /update
{waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=}
0 580
[junit4:junit4] 2> 33214 T1002 C118 P37833 REQ /select
{distrib=false&wt=javabin&q=*:*&version=2} hits=1274 status=0 QTime=0
[junit4:junit4] 2> 33217 T1107 C119 P61275 REQ /select
{distrib=false&wt=javabin&q=*:*&version=2} hits=1274 status=0 QTime=1
[junit4:junit4] 2> 33220 T1002 C118 P37833 REQ /select
{distrib=false&wt=javabin&q=*:*&version=2} hits=1274 status=0 QTime=0
[junit4:junit4] 2> 33223 T1107 C119 P61275 REQ /select
{distrib=false&wt=javabin&q=*:*&version=2} hits=1274 status=0 QTime=0
[junit4:junit4] 2> 33224 T972 oasc.CoreContainer.shutdown Shutting down
CoreContainer instance=945028510
[junit4:junit4] 2> 33224 T972 oasc.SolrCore.close [collection1] CLOSING
SolrCore org.apache.solr.core.SolrCore@62f2c384
[junit4:junit4] 2> 33226 T972 oasc.SolrCore.closeSearcher [collection1]
Closing main searcher on request.
[junit4:junit4] 2> 33227 T972 oasu.DirectUpdateHandler2.close closing
DirectUpdateHandler2{commits=1,autocommits=0,soft
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2468,cumulative_deletesById=1194,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4] 2> 33252 T972 C117 P58945 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4] 2>
commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk-java7/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1342651282324/control/data/index,segFN=segments_2,generation=2,filenames=[_69_3.len,
_7s.fld, _7r_10.len, _7r_3.len, _7n.fld, _7o_4.len, _69_6.len, _69_4.len,
_7l_10.len, _7l_3.len, _7s.si, _69.pst, _7m.inf, _7q_10.len, _7s_6.len,
_7r_4.len, _7r_8.len, _69_1.len, _7l_4.len, _69.inf, _7n.si, _7n_6.len,
_7o_10.len, _7l.fld, _7m.fld, _7p.fld, _7p.pst, _7p_6.len, _7p_1.len,
_7s_4.len, _7r.pst, _7o_3.len, _7l.si, _7l_1.len, _7q.fld, _7o_6.len,
_7m_4.len, _7p_7.len, _7o_1.len, _69_8.len, _7r.si, _7m_6.len, _7p.si, _7n.pst,
_7q_8.len, _7q.si, _7s_1.len, _7m_10.len, _7l_8.len, _7s.pst, _7o.si,
_7n_11.len, _7m_1.liv, _7r_1.len, _7n_4.len, _7q_11.len, _7p_11.len, _7p_3.len,
_7m.si, _69_1.liv, _7o.fld, _7s.inf, _7m_11.len, _7s_7.len, _7p_8.len,
_7m_8.len, _69_11.len, _7n_10.len, _7m.pst, _7r.inf, _7n.inf, _7o.inf,
_7o_7.len, _7r_6.len, _7q.inf, _7q_7.len, _7o.pst, _7n_3.len, _7o_8.len,
_7p_10.len, _7s_11.len, _7q_6.len, _7n_8.len, _7q_4.len, _7r.fld, _7s_3.len,
_69.fld, _7m_1.len, _7o_11.len, _7q_3.len, _7q.pst, _7r_7.len, _69.si, _7l.pst,
_7r_11.len, _7l_11.len, _7n_7.len, _69_7.len, _7p.inf, _7m_3.len, _7p_4.len,
_69_10.len, _7n_1.len, _7s_10.len, _7m_7.len, _7l_7.len, _7l_6.len, _7l.inf,
_7s_8.len, _7q_1.len, segments_2]
[junit4:junit4] 2>
commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk-java7/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1342651282324/control/data/index,segFN=segments_3,generation=3,filenames=[_7t.inf,
_7t.fld, _69_3.len, _7t_8.len, _69_8.len, _7t.si, _69_11.len, _69_6.len,
_69.si, _69_4.len, _7t.pst, _7t_7.len, _69.pst, _7t_10.len, _69_7.len,
_7t_1.len, _69_1.len, _69.inf, _69_10.len, _7t_11.len, _7t_4.len, _7t_3.len,
_69_1.liv, _7t_6.len, segments_3, _69.fld]
[junit4:junit4] 2> 33252 T972 C117 P58945
oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4] 2> 33268 T977 oazs.PrepRequestProcessor.pRequest Processed
session termination for sessionid: 0x1389c40b3990002
[junit4:junit4] 2> 33269 T1026 oascc.ZkStateReader$3.process Updating live
nodes
[junit4:junit4] 2> 33269 T1087 oascc.ZkStateReader$3.process Updating live
nodes
[junit4:junit4] 2> 33269 T1022 oascc.ZkStateReader$3.process Updating live
nodes
[junit4:junit4] 2> 33269 T972 oaz.ZooKeeper.close Session: 0x1389c40b3990002
closed
[junit4:junit4] 2> 33269 T974 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /140.211.11.196:55112 which had sessionid
0x1389c40b3990002
[junit4:junit4] 2> 33269 T992 oascc.ZkStateReader$3.process Updating live
nodes
[junit4:junit4] 2> 33270 T1006 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4:junit4] 2> 33271 T992 oascc.ZkStateReader$3.process WARNING ZooKeeper
watch triggered, but Solr cannot talk to ZK
[junit4:junit4] 2> 33270 T972 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> 33271 T992 oaz.ClientCnxn$EventThread.run EventThread shut
down
[junit4:junit4] 2> 33273 T1006 oasc.Overseer.<init> Overseer
(id=87991994438713347-127.0.0.1:37833_solr-n_0000000001) starting
[junit4:junit4] 2> 33273 T977 oazs.PrepRequestProcessor.pRequest Got
user-level KeeperException when processing sessionid:0x1389c40b3990003
type:create cxid:0x60 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 33274 T977 oazs.PrepRequestProcessor.pRequest Got
user-level KeeperException when processing sessionid:0x1389c40b3990003
type:create cxid:0x61 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 33283 T977 oazs.PrepRequestProcessor.pRequest Got
user-level KeeperException when processing sessionid:0x1389c40b3990003
type:create cxid:0x62 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 33285 T977 oazs.PrepRequestProcessor.pRequest Got
user-level KeeperException when processing sessionid:0x1389c40b3990003
type:create cxid:0x63 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 33286 T1006 oascc.ZkStateReader$3.process Updating live
nodes
[junit4:junit4] 2> 33286 T1176 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4:junit4] 2> 33287 T1175 oasc.Overseer$CloudStateUpdater.run Starting
to work on the main queue
[junit4:junit4] 2> 33321 T993 oasc.Overseer$CloudStateUpdater.amILeader
WARNING org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4:junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
[junit4:junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
[junit4:junit4] 2> at
org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:927)
[junit4:junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:289)
[junit4:junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:286)
[junit4:junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:67)
[junit4:junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:286)
[junit4:junit4] 2> at
org.apache.solr.cloud.Overseer$CloudStateUpdater.amILeader(Overseer.java:187)
[junit4:junit4] 2> at
org.apache.solr.cloud.Overseer$CloudStateUpdater.run(Overseer.java:113)
[junit4:junit4] 2> at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] 2>
[junit4:junit4] 2> 33321 T993 oasc.Overseer$CloudStateUpdater.amILeader
According to ZK I (id=87991994438713346-127.0.0.1:58945_solr-n_0000000000) am
no longer a leader.
[junit4:junit4] 2> 33323 T972 oasc.CoreContainer.shutdown Shutting down
CoreContainer instance=1228799801
[junit4:junit4] 2> 33323 T972 oasc.SolrCore.close [collection1] CLOSING
SolrCore org.apache.solr.core.SolrCore@63f8e156
[junit4:junit4] 2> 33324 T972 oasc.SolrCore.closeSearcher [collection1]
Closing main searcher on request.
[junit4:junit4] 2> 33326 T972 oasu.DirectUpdateHandler2.close closing
DirectUpdateHandler2{commits=3,autocommits=0,soft
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2468,cumulative_deletesById=1194,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4] 2> 33368 T972 C118 P37833 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits:num=3
[junit4:junit4] 2>
commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk-java7/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1342651282324/jetty1/index,segFN=segments_3,generation=3,filenames=[_69_3.len,
_6v_10.len, _6t_8.len, _6s_7.len, _69_6.len, _6s_4.len, _69_4.len, _6q_11.len,
_6u_6.len, _6p.pst, _6q_7.len, _6r_3.len, _6q_8.len, _69.pst, _6q_6.len,
_6t_10.len, _6v.inf, _6p_6.len, _6u.pst, _6q_1.len, _69_1.len, _6v.pst,
_6s_8.len, _69.inf, _6t_3.len, _6t_6.len, _6u_7.len, _6r_6.len, _6v_6.len,
_6p_7.len, _6q_10.len, _6s_11.len, _6s_3.len, _6t_7.len, _69_8.len, _6v_7.len,
_6q.si, _6r_1.len, _6v_4.len, _6p_4.len, _6v_3.len, _6s_6.len, _6p_3.len,
_6q.pst, _6s.si, _6p.fld, _6p_1.len, _69_1.liv, _6p.si, _6q_4.len, _6r.si,
_6r_8.len, _69_11.len, _6q.inf, _6u.inf, _6p.inf, _6s.pst, _6t.si, _6s.fld,
_6r_11.len, _6t.fld, _6u_4.len, _6q_3.len, _6r.fld, _6p_11.len, _6t_1.len,
_6v.fld, _6v.si, _6u_11.len, _6r.inf, _6r_4.len, _6q_1.liv, _6q.fld, _6p_8.len,
_6u.si, _69.fld, _6p_10.len, _6t_11.len, _6v_8.len, _69.si, _6u_10.len,
_6u_8.len, _6s_1.len, _69_7.len, _6v_11.len, _6t_4.len, _6u_3.len, _6s.inf,
_6u_1.len, _6t.pst, _69_10.len, _6v_1.len, _6s_10.len, _6r_10.len, _6r_7.len,
_6t.inf, _6u.fld, _6r.pst, segments_3]
[junit4:junit4] 2>
commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk-java7/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1342651282324/jetty1/index,segFN=segments_4,generation=4,filenames=[_7t.inf,
_69_3.len, _7s.fld, _7r_10.len, _7n.fld, _7o_4.len, _7r_3.len, _69_6.len,
_69_4.len, _7t_7.len, _7l_10.len, _7s.si, _7l_3.len, _69.pst, _7m.inf,
_7s_6.len, _7q_10.len, _7r_8.len, _7r_4.len, _69_1.len, _7l_4.len, _69.inf,
_7n_6.len, _7n.si, _7o_10.len, _7l.fld, _7m.fld, _7p.fld, _7p.pst, _7p_6.len,
_7p_1.len, _7t_3.len, _7s_4.len, _7t_6.len, _7o_3.len, _7r.pst, _7l.si,
_7l_1.len, _7q.fld, _7o_6.len, _7m_4.len, _7p_7.len, _7t.fld, _7o_1.len,
_69_8.len, _7m_6.len, _7r.si, _7p.si, _7n.pst, _7q_8.len, _7s_1.len, _7q.si,
_7m_10.len, _7l_8.len, _7s.pst, _7o.si, _7n_11.len, _7m_1.liv, _7r_1.len,
_7t_4.len, _7n_4.len, _7q_11.len, _7p_11.len, _7p_3.len, _7m.si, _69_1.liv,
_7o.fld, _7s.inf, _7s_7.len, _7m_11.len, _7p_8.len, _7m_8.len, _69_11.len,
_7t.pst, _7n_10.len, _7t_10.len, _7m.pst, _7n.inf, _7r.inf, _7o.inf, _7o_7.len,
_7r_6.len, _7q.inf, _7q_7.len, _7t_11.len, _7o.pst, _7n_3.len, _7o_8.len,
_7p_10.len, _7s_11.len, _7q_6.len, _7n_8.len, _7q_4.len, _7s_3.len, _7r.fld,
_69.fld, _7m_1.len, _7o_11.len, _7q_3.len, _7t_8.len, _7q.pst, _7t.si,
_7r_7.len, _69.si, _7l.pst, _7r_11.len, _7l_11.len, _7n_7.len, _69_7.len,
_7p.inf, _7m_3.len, _7t_1.len, _7p_4.len, _69_10.len, _7s_10.len, _7n_1.len,
_7m_7.len, _7l_7.len, _7l_6.len, _7s_8.len, _7l.inf, _7q_1.len, segments_4]
[junit4:junit4] 2>
commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk-java7/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1342651282324/jetty1/index,segFN=segments_5,generation=5,filenames=[_69_3.len,
_7s.fld, _69_8.len, _69_11.len, _69_6.len, _7u_6.len, _69.si, _69_4.len,
_7s.si, _69.pst, _7s_1.len, _69_7.len, _7s_6.len, _69_1.len, _7u_10.len,
_7u_8.len, _69.inf, _7u_1.len, _7u.fld, _7s.pst, _7u.pst, _69_10.len,
_7s_10.len, _7s_11.len, _7u_3.len, _7s_8.len, _7u.inf, _7s_4.len, _7u.si,
_69_1.liv, _7s.inf, _7u_7.len, _7u_11.len, _7s_7.len, _7u_4.len, _7s_3.len,
segments_5, _69.fld]
[junit4:junit4] 2> 33370 T972 C118 P37833
oasc.SolrDeletionPolicy.updateCommits newest commit = 5
[junit4:junit4] 2> 33385 T977 oazs.PrepRequestProcessor.pRequest Processed
session termination for sessionid: 0x1389c40b3990003
[junit4:junit4] 2> 33386 T1006 oascc.ZkStateReader$3.process Updating live
nodes
[junit4:junit4] 2> 33386 T1026 oascc.ZkStateReader$3.process Updating live
nodes
[junit4:junit4] 2> 33386 T1022 oascc.ZkStateReader$3.process Updating live
nodes
[junit4:junit4] 2> 33387 T1006 oascc.ZkStateReader$3.process WARNING
ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4] 2> 33387 T972 oaz.ZooKeeper.close Session: 0x1389c40b3990003
closed
[junit4:junit4] 2> 33387 T1006 oaz.ClientCnxn$EventThread.run EventThread
shut down
[junit4:junit4] 2> 33387 T974 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /140.211.11.196:40788 which had sessionid
0x1389c40b3990003
[junit4:junit4] 2> 33388 T972 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> 33388 T1087 oasu.PeerSync.sync PeerSync: core=collection1
url=127.0.0.1:11418/solr START replicas=[] nUpdates=100
[junit4:junit4] 2> 33389 T1087 oasu.PeerSync.sync PeerSync: core=collection1
url=127.0.0.1:11418/solr DONE. sync succeeded
[junit4:junit4] 2> 33389 T1087 oasc.SyncStrategy.syncToMe try and ask
http://127.0.0.1:37833/solr/collection1/ to sync
[junit4:junit4] 2> 33391 T1108 C119 P61275 oasu.PeerSync.sync PeerSync:
core=collection1 url=127.0.0.1:11418/solr START
replicas=[127.0.0.1:61275/solr/collection1/] nUpdates=100
[junit4:junit4] 2> 33393 T1101 C119 P61275 REQ /get
{distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=1
[junit4:junit4] 2> 33395 T1108 C119 P61275 oasu.PeerSync.handleVersions
PeerSync: core=collection1 url=127.0.0.1:11418/solr Received 100 versions from
127.0.0.1:61275/solr/collection1/
[junit4:junit4] 2> 33396 T1108 C119 P61275 oasu.PeerSync.handleVersions
PeerSync: core=collection1 url=127.0.0.1:11418/solr Our versions are newer.
ourLowThreshold=1407871944048508928 otherHigh=1407871944315895808
[junit4:junit4] 2> 33396 T1108 C119 P61275 oasu.PeerSync.sync PeerSync:
core=collection1 url=127.0.0.1:11418/solr DONE. sync succeeded
[junit4:junit4] 2> 33396 T1108 C119 P61275 REQ /get
{sync=127.0.0.1:61275/solr/collection1/&getVersions=100&distrib=false&qt=/get&wt=javabin&version=2}
status=0 QTime=5
[junit4:junit4] 2> 33397 T1087 oasc.RecoveryStrategy.close WARNING Stopping
recovery for core collection1 zkNodeName=127.0.0.1:61275_solr_collection1
[junit4:junit4] 2> 33398 T1087 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard1
[junit4:junit4] 2> 33401 T977 oazs.PrepRequestProcessor.pRequest Got
user-level KeeperException when processing sessionid:0x1389c40b3990007
type:create cxid:0x4a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 33403 T1087 oascc.ZkStateReader$3.process Updating live
nodes
[junit4:junit4] 2> 33404 T1087 oascc.SolrZkClient.makePath makePath:
/overseer_elect/leader
[junit4:junit4] 2> 33414 T1087 oasc.Overseer.<init> Overseer
(id=87991994438713351-127.0.0.1:61275_solr-n_0000000004) starting
[junit4:junit4] 2> 33415 T977 oazs.PrepRequestProcessor.pRequest Got
user-level KeeperException when processing sessionid:0x1389c40b3990007
type:create cxid:0x52 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 33423 T977 oazs.PrepRequestProcessor.pRequest Got
user-level KeeperException when processing sessionid:0x1389c40b3990007
type:create cxid:0x53 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 33424 T977 oazs.PrepRequestProcessor.pRequest Got
user-level KeeperException when processing sessionid:0x1389c40b3990007
type:create cxid:0x54 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 33425 T977 oazs.PrepRequestProcessor.pRequest Got
user-level KeeperException when processing sessionid:0x1389c40b3990007
type:create cxid:0x55 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error
Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 33435 T1180 oasc.OverseerCollectionProcessor.run Process
current queue of collection creations
[junit4:junit4] 2> 33435 T1179 oasc.Overseer$CloudStateUpdater.run Starting
to work on the main queue
[junit4:junit4] 2> 33436 T1179 oascc.ZkStateReader.updateCloudState Manual
update of cluster state initiated
[junit4:junit4] 2> 33437 T1179 oascc.ZkStateReader.updateCloudState Updating
cloud state from ZooKeeper...
[junit4:junit4] 2> 33440 T972 oasc.CoreContainer.shutdown Shutting down
CoreContainer instance=671762907
[junit4:junit4] 2> 33440 T972 oasc.RecoveryStrategy.close WARNING Stopping
recovery for core collection1 zkNodeName=127.0.0.1:61275_solr_collection1
[junit4:junit4] 2> 33440 T972 oasc.SolrCore.close [collection1] CLOSING
SolrCore org.apache.solr.core.SolrCore@395b8405
[junit4:junit4] 2> 33442 T972 oasc.SolrCore.closeSearcher [collection1]
Closing main searcher on request.
[junit4:junit4] 2> 33443 T972 oasu.DirectUpdateHandler2.close closing
DirectUpdateHandler2{commits=4,autocommits=0,soft
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=1238,cumulative_deletesById=605,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4] 2> 33445 T972 oasc.RecoveryStrategy.close WARNING Stopping
recovery for core collection1 zkNodeName=127.0.0.1:61275_solr_collection1
[junit4:junit4] 2> 33446 T977 oazs.PrepRequestProcessor.pRequest Processed
session termination for sessionid: 0x1389c40b3990007
[junit4:junit4] 2> 33450 T1179 oasc.Overseer$CloudStateUpdater.run WARNING
Overseer cannot talk to ZK
[junit4:junit4] 2> 33451 T1087 oascc.ZkStateReader$3.process Updating live
nodes
[junit4:junit4] 2> 33451 T1022 oascc.ZkStateReader$3.process Updating live
nodes
[junit4:junit4] 2> 33451 T1026 oascc.ZkStateReader$3.process Updating live
nodes
[junit4:junit4] 2> 33451 T1087 oascc.ZkStateReader$3.process WARNING
ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4] 2> 33451 T972 oaz.ZooKeeper.close Session: 0x1389c40b3990007
closed
[junit4:junit4] 2> 33451 T974 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /140.211.11.196:13780 which had sessionid
0x1389c40b3990007
[junit4:junit4] 2> 33451 T1087 oaz.ClientCnxn$EventThread.run EventThread
shut down
[junit4:junit4] 2> 33453 T972 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> 33505 T977 oazs.PrepRequestProcessor.pRequest Processed
session termination for sessionid: 0x1389c40b3990006
[junit4:junit4] 2> 33508 T1026 oaz.ClientCnxn$EventThread.run EventThread
shut down
[junit4:junit4] 2> 33508 T974 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /140.211.11.196:61806 which had sessionid
0x1389c40b3990006
[junit4:junit4] 2> 33508 T972 oaz.ZooKeeper.close Session: 0x1389c40b3990006
closed
[junit4:junit4] 2> 33509 T977 oazs.PrepRequestProcessor.pRequest Processed
session termination for sessionid: 0x1389c40b3990005
[junit4:junit4] 2> 33509 T1022 oaz.ClientCnxn$EventThread.run EventThread
shut down
[junit4:junit4] 2> 33510 T974 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /140.211.11.196:61169 which had sessionid
0x1389c40b3990005
[junit4:junit4] 2> 33509 T972 oaz.ZooKeeper.close Session: 0x1389c40b3990005
closed
[junit4:junit4] 2> 33510 T977 oazs.PrepRequestProcessor.run
PrepRequestProcessor exited loop!
[junit4:junit4] 2> 33510 T976 oazs.SyncRequestProcessor.run
SyncRequestProcessor exited!
[junit4:junit4] 2> 33510 T972 oazs.FinalRequestProcessor.shutdown shutdown of
request processor complete
[junit4:junit4] 2> 33715 T974 oazs.NIOServerCnxn$Factory.run WARNING Ignoring
unexpected runtime exception java.nio.channels.CancelledKeyException
[junit4:junit4] 2> at
sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:73)
[junit4:junit4] 2> at
sun.nio.ch.SelectionKeyImpl.readyOps(SelectionKeyImpl.java:87)
[junit4:junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn$Factory.run(NIOServerCnxn.java:241)
[junit4:junit4] 2>
[junit4:junit4] 2> 33716 T974 oazs.NIOServerCnxn$Factory.run NIOServerCnxn
factory exited run method
[junit4:junit4] 2> 33716 T972 oazs.FinalRequestProcessor.shutdown shutdown of
request processor complete
[junit4:junit4] 2> 33755 T972 oas.SolrTestCaseJ4.tearDown ###Ending
testDistribSearch
[junit4:junit4] 2> 33789 T1175 oasc.Overseer$CloudStateUpdater.amILeader
WARNING org.apache.zookeeper.KeeperException$SessionExpiredException:
KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4:junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:118)
[junit4:junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:42)
[junit4:junit4] 2> at
org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:927)
[junit4:junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:289)
[junit4:junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:286)
[junit4:junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:67)
[junit4:junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:286)
[junit4:junit4] 2> at
org.apache.solr.cloud.Overseer$CloudStateUpdater.amILeader(Overseer.java:187)
[junit4:junit4] 2> at
org.apache.solr.cloud.Overseer$CloudStateUpdater.run(Overseer.java:113)
[junit4:junit4] 2> at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] 2>
[junit4:junit4] 2> 33789 T1175 oasc.Overseer$CloudStateUpdater.amILeader
According to ZK I (id=87991994438713347-127.0.0.1:37833_solr-n_0000000001) am
no longer a leader.
[junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=RecoveryZkTest
-Dtests.method=testDistribSearch -Dtests.seed=7FB20F9C95367834
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=vi_VN
-Dtests.timezone=SystemV/EST5EDT -Dtests.file.encoding=UTF-8
[junit4:junit4] 2>
[junit4:junit4] > (@AfterClass output)
[junit4:junit4] 2> 33825 T972 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4] 2> NOTE: test params are: codec=SimpleText,
sim=DefaultSimilarity, locale=vi_VN, timezone=SystemV/EST5EDT
[junit4:junit4] 2> NOTE: FreeBSD 9.0-RELEASE amd64/Oracle Corporation
1.7.0_04 (64-bit)/cpus=16,threads=9,free=103367592,total=284164096
[junit4:junit4] 2> NOTE: All tests run in this JVM: [TestCodecSupport,
TestJapanesePartOfSpeechStopFilterFactory, TestJapaneseBaseFormFilterFactory,
TestFunctionQuery, TestRemoveDuplicatesTokenFilterFactory,
TestGermanLightStemFilterFactory, SolrInfoMBeanTest, CurrencyFieldTest,
EchoParamsTest, TestSurroundQueryParser,
DistributedQueryElevationComponentTest, TestExtendedDismaxParser,
UpdateRequestProcessorFactoryTest, ConvertedLegacyTest, TestLFUCache,
TestDistributedGrouping, TestPortugueseStemFilterFactory,
TestSwedishLightStemFilterFactory, TestItalianLightStemFilterFactory,
TestFinnishLightStemFilterFactory, TestStemmerOverrideFilterFactory,
SuggesterTest, DateFieldTest, TestPropInject, JSONWriterTest,
TestBrazilianStemFilterFactory, TestWordDelimiterFilterFactory,
TestCJKWidthFilterFactory, TestJoin, TermsComponentTest, TestArabicFilters,
FullSolrCloudDistribCmdsTest, DistributedSpellCheckComponentTest,
SolrCmdDistributorTest, NumericFieldsTest,
TestPortugueseMinimalStemFilterFactory, TestIrishLowerCaseFilterFactory,
SnowballPorterFilterFactoryTest, TestDFRSimilarityFactory,
TestGreekLowerCaseFilterFactory, LegacyHTMLStripCharFilterTest,
BinaryUpdateRequestHandlerTest, CommonGramsQueryFilterFactoryTest,
TestFiltering, DateMathParserTest, MinimalSchemaTest,
TestThaiWordFilterFactory, TestSolrQueryParser, SuggesterWFSTTest,
TestSynonymFilterFactory, TestPatternTokenizerFactory, SpatialFilterTest,
BasicDistributedZkTest, DirectSolrSpellCheckerTest, BadComponentTest,
TestTrimFilterFactory, IndexBasedSpellCheckerTest, FastVectorHighlighterTest,
RecoveryZkTest]
[junit4:junit4] 2>
[junit4:junit4] Completed on J0 in 33.83s, 1 test, 1 error <<< FAILURES!
[...truncated 489 lines...]
[...truncated 37979 lines...]
[...truncated 37979 lines...]
[...truncated 8301 lines...]
check-forbidden-apis:
validate:
[echo] Building solr...
compile-tools:
ivy-availability-check:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk-java7/checkout/lucene/ivy-settings.xml
resolve:
init:
compile-core:
resolve-example:
[echo] Building solr-example...
ivy-availability-check:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk-java7/checkout/lucene/ivy-settings.xml
resolve:
[echo] Building solr-example-DIH...
ivy-availability-check:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk-java7/checkout/lucene/ivy-settings.xml
resolve:
resolve:
[echo] Building solr-core...
ivy-availability-check:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk-java7/checkout/lucene/ivy-settings.xml
resolve:
[echo] Building solr-solrj...
ivy-availability-check:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk-java7/checkout/lucene/ivy-settings.xml
resolve:
[echo] Building solr-test-framework...
ivy-availability-check:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk-java7/checkout/lucene/ivy-settings.xml
resolve:
[echo] Building solr-analysis-extras...
ivy-availability-check:
[...truncated 4781 lines...]
check-forbidden-apis:
validate:
test:
test-core:
jflex-uptodate-check:
jflex-notice:
javacc-uptodate-check:
javacc-notice:
ivy-availability-check:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk-java7/checkout/lucene/ivy-settings.xml
resolve:
init:
-clover.disable:
-clover.setup:
clover:
common.compile-core:
compile-core:
compile-test-framework:
ivy-availability-check:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk-java7/checkout/lucene/ivy-settings.xml
resolve:
init:
compile-lucene-core:
jflex-uptodate-check:
jflex-notice:
javacc-uptodate-check:
javacc-notice:
ivy-availability-check:
ivy-fail:
ivy-configure:
[ivy:configure] :: loading settings :: file =
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk-java7/checkout/lucene/ivy-settings.xml
resolve:
init:
-clover.disable:
-clover.setup:
clover:
[...truncated 24745 lines...]
[...truncated 37979 lines...]
[...truncated 37966 lines...]
BUILD FAILED
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk-java7/checkout/build.xml:29:
The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk-java7/checkout/solr/build.xml:144:
The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk-java7/checkout/lucene/module-build.xml:62:
The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk-java7/checkout/lucene/common-build.xml:1093:
The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk-java7/checkout/lucene/common-build.xml:788:
There were test failures: 296 suites, 1051 tests, 1 error, 5 ignored
Total time: 27 minutes 25 seconds
Build step 'Execute shell' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
