lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Solr-4.x - Build # 34 - Failure
Date Wed, 11 Jul 2012 08:46:27 GMT
Build: https://builds.apache.org/job/Solr-4.x/34/

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([18FCBFE19AC2B6AC]: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.RAMDirectory.listAll(RAMDirectory.java:107)
	at org.apache.lucene.store.NRTCachingDirectory.listAll(NRTCachingDirectory.java:124)
	at org.apache.lucene.index.IndexFileDeleter.refresh(IndexFileDeleter.java:320)
	at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:3158)
	at org.apache.lucene.index.ConcurrentMergeScheduler.doMerge(ConcurrentMergeScheduler.java:382)
	at org.apache.lucene.index.ConcurrentMergeScheduler$MergeThread.run(ConcurrentMergeScheduler.java:451)




Build Log:
[...truncated 49020 lines...]
[junit4:junit4]   2> 36138 T44 C4 P58462 /update {wt=javabin&version=2} {add=[11294
(1407182192494247936)]} 0 0
[junit4:junit4]   2> 36141 T132 C6 P18803 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2}
status=0 QTime=1 
[junit4:junit4]   2> 36141 T58 C5 P11765 /update {wt=javabin&version=2} {add=[1049
(1407182192493199360)]} 0 4
[junit4:junit4]   2> 36142 T30 oascc.ZkStateReader.updateCloudState Manual update of cluster
state initiated
[junit4:junit4]   2> 36142 T30 oascc.ZkStateReader.updateCloudState Updating cloud state
from ZooKeeper... 
[junit4:junit4]   2> 36143 T44 C4 P58462 /update {wt=javabin&version=2} {delete=[995
(-1407182192499490816)]} 0 0
[junit4:junit4]   2> 36144 T132 C6 P18803 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2}
status=0 QTime=1 
[junit4:junit4]   2> 36144 T59 C5 P11765 /update {wt=javabin&version=2} {add=[11294
(1407182192497393664)]} 0 3
[junit4:junit4]   2> 36147 T44 C4 P58462 /update {wt=javabin&version=2} {add=[11295
(1407182192502636544)]} 0 1
[junit4:junit4]   2> 36147 T132 C6 P18803 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2}
status=0 QTime=0 
[junit4:junit4]   2> 36147 T58 C5 P11765 /update {wt=javabin&version=2} {delete=[995
(-1407182192501587968)]} 0 2
[junit4:junit4]   2> 36150 T44 C4 P58462 /update {wt=javabin&version=2} {add=[1050
(1407182192506830848)]} 0 0
[junit4:junit4]   2> 36152 T132 C6 P18803 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2}
status=0 QTime=0 
[junit4:junit4]   2> 36152 T59 C5 P11765 /update {wt=javabin&version=2} {add=[11295
(1407182192505782272)]} 0 3
[junit4:junit4]   2> added docs:1295 with 0 fails deletes:634
[junit4:junit4]   2> 36155 T132 C6 P18803 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2}
status=0 QTime=0 
[junit4:junit4]   2> 36156 T58 C5 P11765 /update {wt=javabin&version=2} {add=[1050
(1407182192509976576)]} 0 3
[junit4:junit4]   2> added docs:1050 with 0 fails deletes:519
[junit4:junit4]   2> 36158 T44 C4 P58462 oasu.DirectUpdateHandler2.commit start commit{flags=0,version=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 36350 T44 C4 P58462 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit:
commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Solr-4.x/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1341993479358/control/data/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@771b16a7; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Solr-4.x/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1341993479358/control/data/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@771b16a7; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_7e.fdx,
_7d_Lucene40WithOrds_0.tii, _7d_nrm.cfs, _7d_SimpleText_0.pst, _7e.fdt, _7e_SimpleText_0.pst,
_7e_Pulsing40_0.tip, _69.fdx, _7e_Pulsing40_0.tim, _7f_Pulsing40_0.frq, _7d.fnm, _7d_nrm.cfe,
_7f_Pulsing40_0.prx, _69.fdt, _7d.si, _7e_Lucene40WithOrds_0.prx, _69_Pulsing40_0.tim, _7e_Memory_0.ram,
_69_Pulsing40_0.tip, _7d_Pulsing40_0.frq, _69_1.del, _7d_Lucene40WithOrds_0.prx, _69_Lucene40WithOrds_0.prx,
_7e_Lucene40WithOrds_0.frq, _7e_Pulsing40_0.frq, _7f_Memory_0.ram, _7e_Pulsing40_0.prx, _7f.fnm,
_7e_Lucene40WithOrds_0.tii, _7f.si, _69_Lucene40WithOrds_0.frq, _69_Memory_0.ram, _7e_Lucene40WithOrds_0.tib,
_69_Lucene40WithOrds_0.tib, _7e_nrm.cfs, _69.si, _7d_Pulsing40_0.tip, _7d_Memory_0.ram, _7d_Pulsing40_0.tim,
_7f_nrm.cfs, _69_Lucene40WithOrds_0.tii, _7f.fdx, _7f_Lucene40WithOrds_0.prx, _7d.fdt, _7d_Pulsing40_0.prx,
_7f.fdt, _7f_nrm.cfe, _7e_nrm.cfe, _7d.fdx, _69_SimpleText_0.pst, _7d_Lucene40WithOrds_0.frq,
_7e_1.del, _69_Pulsing40_0.prx, _7e.si, _69_nrm.cfe, _69_nrm.cfs, _7e.fnm, _7f_SimpleText_0.pst,
_7f_Lucene40WithOrds_0.tib, _69.fnm, _69_Pulsing40_0.frq, segments_2, _7f_Lucene40WithOrds_0.frq,
_7f_Lucene40WithOrds_0.tii, _7d_Lucene40WithOrds_0.tib, _7f_Pulsing40_0.tim, _7f_Pulsing40_0.tip]
[junit4:junit4]   2> 36350 T44 C4 P58462 oasc.SolrDeletionPolicy.updateCommits newest commit
= 2
[junit4:junit4]   2> 36362 T44 C4 P58462 oass.SolrIndexSearcher.<init> Opening Searcher@11671cbc
main
[junit4:junit4]   2> 36362 T44 C4 P58462 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 36364 T54 oasc.SolrCore.registerSearcher [collection1] Registered
new searcher Searcher@11671cbc main{StandardDirectoryReader(segments_2:570 _69(4.0):C1006/68
_7e(4.0):C246/7 _7d(4.0):C9 _7f(4.0):C6)}
[junit4:junit4]   2> 36365 T44 C4 P58462 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2}
{commit=} 0 207
[junit4:junit4]   2> 36368 T58 C5 P11765 oasu.DirectUpdateHandler2.commit start commit{flags=0,version=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 36693 T58 C5 P11765 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit:
commits:num=3
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Solr-4.x/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1341993479358/jetty1/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2321dba8; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_3,generation=3,filenames=[_3g_Pulsing40_0.frq,
_3g_SimpleText_0.pst, _3f_Pulsing40_0.tim, _3f_Pulsing40_0.tip, _3e_Lucene40WithOrds_0.prx,
_3e_Lucene40WithOrds_0.frq, _3f_Lucene40WithOrds_0.frq, _3h_Lucene40WithOrds_0.frq, _3c_Pulsing40_0.tim,
_3e_Pulsing40_0.prx, _3c_Memory_0.ram, _3d_SimpleText_0.pst, _3d_Lucene40WithOrds_0.prx, _3e_Lucene40WithOrds_0.tii,
_3e_Pulsing40_0.frq, _3d_Memory_0.ram, _3c_nrm.cfe, _3e_Lucene40WithOrds_0.tib, _3h.fdx, _3h.fnm,
_3h_Pulsing40_0.prx, _3c_Pulsing40_0.prx, _3e_Pulsing40_0.tim, _3c_nrm.cfs, _3f.fnm, _3d_Lucene40WithOrds_0.frq,
_3h_Pulsing40_0.frq, _3h_SimpleText_0.pst, _3e_Pulsing40_0.tip, _3g_Lucene40WithOrds_0.tii,
_3e_SimpleText_0.pst, _3d_1.del, _3h.fdt, _3g_Lucene40WithOrds_0.tib, _3c_Lucene40WithOrds_0.prx,
_3e.fdx, _3e_nrm.cfe, _3e.fdt, _3f_SimpleText_0.pst, _3f_Lucene40WithOrds_0.prx, _3d.fdx,
_3d.fdt, _3c_Pulsing40_0.tip, _3e_nrm.cfs, _3f_nrm.cfs, _3d_Lucene40WithOrds_0.tib, _3f.fdx,
_3d_Lucene40WithOrds_0.tii, _3f_nrm.cfe, _3f.fdt, _3c_Lucene40WithOrds_0.tii, _3d.si, _3d_Pulsing40_0.frq,
_3c_Pulsing40_0.frq, _3c_Lucene40WithOrds_0.tib, _3g_Lucene40WithOrds_0.prx, _3g_Pulsing40_0.prx,
_3d_Pulsing40_0.tim, _3h_Memory_0.ram, _3c.fdx, _3f.si, _3f_Memory_0.ram, _3f_Pulsing40_0.prx,
_3c.fdt, _3g.fdt, _3h.si, _3d_nrm.cfe, _3g.fdx, _3g_Memory_0.ram, _3g_Lucene40WithOrds_0.frq,
_3d_nrm.cfs, _3h_Lucene40WithOrds_0.prx, _3e.fnm, _3f_Lucene40WithOrds_0.tib, _3f_Lucene40WithOrds_0.tii,
_3f_Pulsing40_0.frq, _3g_Pulsing40_0.tim, _3h_nrm.cfe, _3h_Lucene40WithOrds_0.tib, _3g.si,
_3g_Pulsing40_0.tip, _3c.fnm, _3d.fnm, _3h_Lucene40WithOrds_0.tii, _3c_Lucene40WithOrds_0.frq,
_3e_Memory_0.ram, _3g_nrm.cfe, _3h_nrm.cfs, _3h_Pulsing40_0.tip, _3d_Pulsing40_0.prx, _3g_nrm.cfs,
_3h_Pulsing40_0.tim, _3g.fnm, _3c.si, _3d_Pulsing40_0.tip, _3e.si, _3c_SimpleText_0.pst, segments_3]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Solr-4.x/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1341993479358/jetty1/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2321dba8; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_4,generation=4,filenames=[_6a_Pulsing40_0.prx,
_60_Pulsing40_0.frq, _68.si, _62_Lucene40WithOrds_0.frq, _60_Memory_0.ram, _66_Pulsing40_0.tim,
_66.si, _64_Lucene40WithOrds_0.tib, _65_Pulsing40_0.tip, _65_Pulsing40_0.tim, _64_Lucene40WithOrds_0.tii,
_60.fnm, _66_Pulsing40_0.tip, _6b_Lucene40WithOrds_0.tii, _62.fdx, _6a_Lucene40WithOrds_0.tib,
_61_Pulsing40_0.prx, _62.fdt, _6a_Lucene40WithOrds_0.frq, _6a_Pulsing40_0.frq, _6a_Lucene40WithOrds_0.tii,
_6b_Pulsing40_0.prx, _62_Pulsing40_0.prx, _60_Pulsing40_0.tip, _62_Lucene40WithOrds_0.prx,
_68_SimpleText_0.pst, _64.fdx, _63_SimpleText_0.pst, _61.fdt, _62_nrm.cfs, _61.fdx, _6c.fnm,
_60_Pulsing40_0.tim, _64.fdt, _61_SimpleText_0.pst, _6a_nrm.cfe, _68.fnm, _6c_Lucene40WithOrds_0.tii,
_62_nrm.cfe, _66_Memory_0.ram, _63.fnm, _67_Lucene40WithOrds_0.frq, _6a_nrm.cfs, _65_Lucene40WithOrds_0.tii,
_6c_SimpleText_0.pst, _65_Lucene40WithOrds_0.tib, _67_Memory_0.ram, _67_Lucene40WithOrds_0.prx,
_68_Memory_0.ram, _68_Lucene40WithOrds_0.tii, _68_Lucene40WithOrds_0.tib, _6b_Lucene40WithOrds_0.frq,
_6b.si, _6c_Lucene40WithOrds_0.tib, _63_Pulsing40_0.tim, _60_nrm.cfs, _65_Lucene40WithOrds_0.prx,
_63_Pulsing40_0.tip, _63.fdt, _67_Pulsing40_0.prx, _63.fdx, _68_nrm.cfs, _63_Pulsing40_0.prx,
_64_SimpleText_0.pst, _67_nrm.cfe, _64_Lucene40WithOrds_0.prx, _61.fnm, _6c_nrm.cfs, _61_Lucene40WithOrds_0.tib,
_6b.fdx, _6b_nrm.cfs, _63_Lucene40WithOrds_0.tib, _6b.fdt, _63_Lucene40WithOrds_0.tii, _66_Lucene40WithOrds_0.tib,
_6c_nrm.cfe, _6b_nrm.cfe, _6c_Lucene40WithOrds_0.prx, _61_Lucene40WithOrds_0.tii, _66_Lucene40WithOrds_0.tii,
_64_Memory_0.ram, _67.fdx, _6a.fdx, _67.fdt, _6a.fdt, _6b_Memory_0.ram, _66.fnm, _6b_Pulsing40_0.frq,
_60_SimpleText_0.pst, _63_Pulsing40_0.frq, _67_nrm.cfs, _6c_Lucene40WithOrds_0.frq, _6a.si,
_64_Lucene40WithOrds_0.frq, _6b_SimpleText_0.pst, _60_nrm.cfe, _68_nrm.cfe, _68_Pulsing40_0.prx,
_62_Pulsing40_0.frq, _66_Lucene40WithOrds_0.prx, _6b_Pulsing40_0.tim, _66_nrm.cfe, _6b_Pulsing40_0.tip,
_61_2.del, _6c_Pulsing40_0.tim, _64_nrm.cfs, _65.fdt, _6c_Pulsing40_0.tip, _65.fdx, _63_Memory_0.ram,
_66_Pulsing40_0.prx, _67.si, _65_nrm.cfs, _68_Pulsing40_0.tim, _62.si, _63.si, _64.si, _6c_Pulsing40_0.frq,
_66.fdx, _68_Pulsing40_0.tip, _66.fdt, _65_Memory_0.ram, _60.si, _62_Lucene40WithOrds_0.tib,
_67_SimpleText_0.pst, _68.fdt, _62_Lucene40WithOrds_0.tii, _65_nrm.cfe, _68.fdx, _68_Pulsing40_0.frq,
_61_Lucene40WithOrds_0.prx, _62_SimpleText_0.pst, _64_nrm.cfe, _6a_Pulsing40_0.tim, _6a_Pulsing40_0.tip,
_61_Memory_0.ram, _6c.fdx, _62_Memory_0.ram, _63_Lucene40WithOrds_0.frq, _66_nrm.cfs, _61_Pulsing40_0.frq,
_6c.fdt, _66_SimpleText_0.pst, _63_nrm.cfs, _65_SimpleText_0.pst, _65_Lucene40WithOrds_0.frq,
_67_Pulsing40_0.frq, _6c_Memory_0.ram, _60.fdx, _6a_SimpleText_0.pst, _60.fdt, _65.si, _68_Lucene40WithOrds_0.prx,
_63_Lucene40WithOrds_0.prx, _6a_Lucene40WithOrds_0.prx, _60_Lucene40WithOrds_0.prx, _6a_Memory_0.ram,
_6b_Lucene40WithOrds_0.prx, _60_Lucene40WithOrds_0.tib, _61_Pulsing40_0.tim, _64_Pulsing40_0.frq,
_61_nrm.cfs, _61_Pulsing40_0.tip, _60_Lucene40WithOrds_0.tii, _68_Lucene40WithOrds_0.frq,
_67_Lucene40WithOrds_0.tib, _61_nrm.cfe, _62_Pulsing40_0.tip, _65.fnm, _6c_Pulsing40_0.prx,
_67_Lucene40WithOrds_0.tii, _62_Pulsing40_0.tim, _6b_Lucene40WithOrds_0.tib, _60_Pulsing40_0.prx,
_64_Pulsing40_0.tip, _64_Pulsing40_0.tim, _6c.si, _61.si, _61_Lucene40WithOrds_0.frq, _64_Pulsing40_0.prx,
_66_Pulsing40_0.frq, _67_Pulsing40_0.tip, _6b.fnm, _62.fnm, _65_Pulsing40_0.frq, _65_Pulsing40_0.prx,
_67_Pulsing40_0.tim, _64.fnm, _63_nrm.cfe, _67.fnm, _60_Lucene40WithOrds_0.frq, _66_Lucene40WithOrds_0.frq,
_6a.fnm, segments_4]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Solr-4.x/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1341993479358/jetty1/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2321dba8; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_5,generation=5,filenames=[_7e.fdx,
_7d_Lucene40WithOrds_0.tii, _7d_nrm.cfs, _7d_SimpleText_0.pst, _7e.fdt, _7e_SimpleText_0.pst,
_7g_Lucene40WithOrds_0.prx, _7e_Pulsing40_0.tip, _7e_Pulsing40_0.tim, _7f_Pulsing40_0.frq,
_7d.fnm, _7d_nrm.cfe, _7f_Pulsing40_0.prx, _6h_Pulsing40_0.prx, _7d.si, _7e_Lucene40WithOrds_0.prx,
_6h_nrm.cfe, _6h.si, _7g_Lucene40WithOrds_0.tib, _7e_Memory_0.ram, _7g_Memory_0.ram, _7g.fnm,
_7d_Pulsing40_0.frq, _7d_Lucene40WithOrds_0.prx, _6h_SimpleText_0.pst, _7e_Lucene40WithOrds_0.frq,
_6h_Pulsing40_0.frq, _7g_Lucene40WithOrds_0.tii, _7e_Pulsing40_0.frq, _7f_Memory_0.ram, _6h_nrm.cfs,
_7e_Pulsing40_0.prx, _7g_Pulsing40_0.tip, _7g_Lucene40WithOrds_0.frq, _7f.fnm, _7e_Lucene40WithOrds_0.tii,
_7f.si, _6h_Lucene40WithOrds_0.frq, _7e_Lucene40WithOrds_0.tib, _7g_SimpleText_0.pst, _6h_1.del,
_7g_Pulsing40_0.tim, _6h.fnm, _7e_nrm.cfs, _7d_Pulsing40_0.tip, _7g_Pulsing40_0.frq, _7d_Memory_0.ram,
_7d_Pulsing40_0.tim, _7f_nrm.cfs, _6h_Pulsing40_0.tip, _7f.fdx, _7f_Lucene40WithOrds_0.prx,
_7d.fdt, _7d_Pulsing40_0.prx, _7f.fdt, _7f_nrm.cfe, _6h.fdt, _7e_nrm.cfe, _7d.fdx, _7d_Lucene40WithOrds_0.frq,
_6h.fdx, _7e_1.del, _6h_Lucene40WithOrds_0.tib, _7e.si, _7g.fdt, _7g_Pulsing40_0.prx, _6h_Memory_0.ram,
_7g_nrm.cfs, _7g.fdx, _7e.fnm, _6h_Lucene40WithOrds_0.tii, _7g.si, _7f_SimpleText_0.pst, _6h_Pulsing40_0.tim,
_7f_Lucene40WithOrds_0.tib, _6h_Lucene40WithOrds_0.prx, _7f_Lucene40WithOrds_0.frq, _7g_nrm.cfe,
_7f_Lucene40WithOrds_0.tii, segments_5, _7d_Lucene40WithOrds_0.tib, _7f_Pulsing40_0.tim, _7f_Pulsing40_0.tip]
[junit4:junit4]   2> 36696 T58 C5 P11765 oasc.SolrDeletionPolicy.updateCommits newest commit
= 5
[junit4:junit4]   2> 36740 T58 C5 P11765 oass.SolrIndexSearcher.<init> Opening Searcher@6f0b5b98
main
[junit4:junit4]   2> 36742 T58 C5 P11765 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 36743 T66 oasc.SolrCore.registerSearcher [collection1] Registered
new searcher Searcher@6f0b5b98 main{StandardDirectoryReader(segments_5:575 _6h(4.0):C1037/64
_7e(4.0):C205/14 _7d(4.0):C10 _7f(4.0):C10 _7g(4.0):C8)}
[junit4:junit4]   2> 36749 T132 C6 P18803 oasu.DirectUpdateHandler2.commit start commit{flags=0,version=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 37003 T132 C6 P18803 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit:
commits:num=2
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Solr-4.x/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1341993479358/jetty2/index.20120711075832951
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6aa71b2e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_5,generation=5,filenames=[_7e.fdx,
_78_Lucene40WithOrds_0.prx, _6r_Pulsing40_0.tim, _79_Lucene40WithOrds_0.prx, _7e.fdt, _6r_Pulsing40_0.tip,
_7a.si, _6f.fdt, _6t_nrm.cfe, _7b.fnm, _77_1.del, _7a_Memory_0.ram, _77_nrm.cfe, _78.fnm,
_6f.fdx, _7e_Lucene40WithOrds_0.prx, _6x_nrm.cfe, _6f_Lucene40WithOrds_0.frq, _7a_Pulsing40_0.prx,
_77_nrm.cfs, _79.fdt, _7d_Pulsing40_0.frq, _6v_Memory_0.ram, _79.fdx, _7e_Lucene40WithOrds_0.frq,
_7c_Pulsing40_0.tip, _77_Pulsing40_0.prx, _7c_Pulsing40_0.tim, _6r_SimpleText_0.pst, _6r_Lucene40WithOrds_0.tii,
_6w.si, _6v_1.del, _6f_nrm.cfe, _7c_Lucene40WithOrds_0.tib, _78_Lucene40WithOrds_0.tib, _6f_nrm.cfs,
_6r_Lucene40WithOrds_0.tib, _6s_SimpleText_0.pst, _6r_nrm.cfs, _78_Lucene40WithOrds_0.tii,
_79.si, _6v_Lucene40WithOrds_0.prx, _6t_Lucene40WithOrds_0.frq, _6w.fdt, _6w.fdx, _7b_Lucene40WithOrds_0.tii,
_7c_Lucene40WithOrds_0.tii, _77_Lucene40WithOrds_0.frq, _6r_Memory_0.ram, _6v_Lucene40WithOrds_0.tii,
_6x_Pulsing40_0.frq, _7a.fnm, _6v_Lucene40WithOrds_0.tib, _6t_SimpleText_0.pst, _7b_SimpleText_0.pst,
_7c_Lucene40WithOrds_0.frq, _6t.fdx, _6w_Pulsing40_0.tip, _6s_Pulsing40_0.frq, _7a_Lucene40WithOrds_0.frq,
_6t.fdt, _6w_Lucene40WithOrds_0.frq, _78_SimpleText_0.pst, _7b_Pulsing40_0.tip, _7b_Pulsing40_0.tim,
_7e_Pulsing40_0.tip, _6x_1.del, _77.fdx, _7e_Pulsing40_0.tim, _6f_Lucene40WithOrds_0.prx,
_6t.si, _6v.fdx, _6w_Pulsing40_0.tim, _6v_SimpleText_0.pst, _6v.fdt, _77_SimpleText_0.pst,
_77.fdt, _6u.fnm, _7e_Memory_0.ram, _6r.fdx, _6s.fdx, _6u_Pulsing40_0.frq, _77_Lucene40WithOrds_0.tii,
_6r.fdt, _7c_Pulsing40_0.frq, _7d_Lucene40WithOrds_0.prx, _6v.si, _77_Lucene40WithOrds_0.tib,
_7b_Pulsing40_0.frq, _7b_Lucene40WithOrds_0.frq, _6u_Pulsing40_0.tim, _6r_nrm.cfe, _79_Memory_0.ram,
_7c_nrm.cfe, _6s.fdt, _6u_Pulsing40_0.tip, _6t_Pulsing40_0.frq, _7e_Pulsing40_0.frq, _7c_nrm.cfs,
_6x_Lucene40WithOrds_0.tib, _6x_Lucene40WithOrds_0.tii, _7e_Lucene40WithOrds_0.tii, _7b_Memory_0.ram,
_79_Pulsing40_0.frq, _7e_Lucene40WithOrds_0.tib, _6u_Lucene40WithOrds_0.frq, _77_Pulsing40_0.tip,
_78_Pulsing40_0.tim, _7b_nrm.cfs, _6x.si, _78_Pulsing40_0.frq, _6v_Pulsing40_0.prx, _6f_SimpleText_0.pst,
_7d.fdt, _6r_Lucene40WithOrds_0.prx, _7d.fdx, _7b_nrm.cfe, _6u_Memory_0.ram, _7c_Memory_0.ram,
_78_Pulsing40_0.tip, _7a_Lucene40WithOrds_0.prx, _77_Pulsing40_0.tim, _6s_Lucene40WithOrds_0.prx,
_79_Lucene40WithOrds_0.frq, _6x_nrm.cfs, _79_Pulsing40_0.tip, _6t_nrm.cfs, _6f_Pulsing40_0.prx,
_6u_Lucene40WithOrds_0.prx, _6t_Lucene40WithOrds_0.tii, _6t_Lucene40WithOrds_0.tib, _6x.fnm,
_7c.fdt, _79_Pulsing40_0.tim, _7c.fdx, _7b_Lucene40WithOrds_0.prx, _77_Pulsing40_0.frq, _6s_nrm.cfs,
_7d_nrm.cfs, _6v_Lucene40WithOrds_0.frq, _7d_SimpleText_0.pst, _6w.fnm, _6f_Memory_0.ram,
_7e_SimpleText_0.pst, _7b.si, _7d_nrm.cfe, _6u_Lucene40WithOrds_0.tii, _79_Pulsing40_0.prx,
_6u_SimpleText_0.pst, _6t_1.del, _7d.si, _6u_Lucene40WithOrds_0.tib, _6w_Memory_0.ram, _6s_Pulsing40_0.prx,
_7c_Lucene40WithOrds_0.prx, _79_Lucene40WithOrds_0.tib, _79_Lucene40WithOrds_0.tii, _6r_1.del,
_6s_Lucene40WithOrds_0.frq, _78.fdx, _6f_Lucene40WithOrds_0.tib, _78.fdt, _7b.fdx, _7b.fdt,
_6f_Lucene40WithOrds_0.tii, _7a_Pulsing40_0.tim, _6t_Pulsing40_0.prx, _7d_Pulsing40_0.tip,
_7a_Pulsing40_0.tip, _7d_Pulsing40_0.tim, _6f.fnm, _7c_Pulsing40_0.prx, _7a.fdx, _7d_Lucene40WithOrds_0.frq,
_7a.fdt, _6t_Memory_0.ram, _7e.si, _6w_Lucene40WithOrds_0.prx, _6x.fdt, _6r_Pulsing40_0.prx,
_6x.fdx, _6s.si, _6w_nrm.cfe, _77.si, _6v_Pulsing40_0.tip, _6w_Pulsing40_0.prx, _7e.fnm, _6v_Pulsing40_0.tim,
_6u_nrm.cfe, _7a_SimpleText_0.pst, _78_Pulsing40_0.prx, _6f_Pulsing40_0.frq, _6v_nrm.cfe,
_6s_Pulsing40_0.tip, _6r.si, _7d_Lucene40WithOrds_0.tii, _6v_nrm.cfs, _6s_Memory_0.ram, _79_nrm.cfs,
_6s_Pulsing40_0.tim, _6f_Pulsing40_0.tim, _7b_Lucene40WithOrds_0.tib, _6t_Lucene40WithOrds_0.prx,
_6u_nrm.cfs, _6f_1.del, _7d.fnm, _7c.si, _6x_Pulsing40_0.prx, _6r_Lucene40WithOrds_0.frq,
_6w_nrm.cfs, _6x_Lucene40WithOrds_0.prx, _78_Lucene40WithOrds_0.frq, _7a_Pulsing40_0.frq,
_7a_nrm.cfs, _6w_Pulsing40_0.frq, _6v_Pulsing40_0.frq, _6f.si, _7b_Pulsing40_0.prx, _7a_nrm.cfe,
_6w_1.del, _6f_Pulsing40_0.tip, _6w_Lucene40WithOrds_0.tib, _79_nrm.cfe, _6s_1.del, _7e_Pulsing40_0.prx,
_6u.si, _7c.fnm, _6w_Lucene40WithOrds_0.tii, _77.fnm, _6r.fnm, _6u_Pulsing40_0.prx, _6x_Pulsing40_0.tim,
_7a_Lucene40WithOrds_0.tib, _78_nrm.cfs, _7e_nrm.cfs, _6x_Lucene40WithOrds_0.frq, _6t.fnm,
_7d_Memory_0.ram, _6x_SimpleText_0.pst, _6w_SimpleText_0.pst, _77_Lucene40WithOrds_0.prx,
_6s.fnm, _6s_Lucene40WithOrds_0.tii, _78_Memory_0.ram, _7d_Pulsing40_0.prx, _6s_Lucene40WithOrds_0.tib,
_7e_nrm.cfe, _6x_Pulsing40_0.tip, _6u_1.del, _77_Memory_0.ram, _6u.fdt, _6t_Pulsing40_0.tip,
_78.si, _6t_Pulsing40_0.tim, _6u.fdx, _79_SimpleText_0.pst, _7c_SimpleText_0.pst, _6r_Pulsing40_0.frq,
_78_nrm.cfe, _7a_Lucene40WithOrds_0.tii, _7d_Lucene40WithOrds_0.tib, segments_5, _6v.fnm,
_6s_nrm.cfe, _6x_Memory_0.ram, _79.fnm]
[junit4:junit4]   2> 		commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Solr-4.x/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1341993479358/jetty2/index.20120711075832951
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6aa71b2e; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_6,generation=6,filenames=[_7i.si,
_7i_Lucene40WithOrds_0.tib, _7h_SimpleText_0.pst, _7h_Lucene40WithOrds_0.tii, _6y_SimpleText_0.pst,
_7h.si, _7h_Lucene40WithOrds_0.tib, _7h_Memory_0.ram, _7i_Lucene40WithOrds_0.tii, _7h_Pulsing40_0.tip,
_7i_Lucene40WithOrds_0.frq, _7g_Lucene40WithOrds_0.prx, _7h_Lucene40WithOrds_0.frq, _7f_Pulsing40_0.frq,
_6y.fnm, _7f_Pulsing40_0.prx, _6y.si, _7i_Memory_0.ram, _6y_Memory_0.ram, _6y_Pulsing40_0.prx,
_7g_Lucene40WithOrds_0.tib, _7g_Memory_0.ram, _7h_Lucene40WithOrds_0.prx, _7g.fnm, _6y_nrm.cfe,
_7h_nrm.cfe, _7h.fnm, _7i.fdx, _7g_Lucene40WithOrds_0.tii, _6y_nrm.cfs, _7i_SimpleText_0.pst,
_7i.fdt, _7f_Memory_0.ram, _7h_nrm.cfs, _7g_Pulsing40_0.tip, _7f.fnm, _7g_Lucene40WithOrds_0.frq,
_7i_Pulsing40_0.tip, _7f.si, _7i_Pulsing40_0.tim, _6y_Pulsing40_0.frq, _7g_SimpleText_0.pst,
_7g_Pulsing40_0.tim, _7g_Pulsing40_0.frq, _7f_nrm.cfs, _7f.fdx, _6y_Pulsing40_0.tip, _7f_Lucene40WithOrds_0.prx,
_6y_Pulsing40_0.tim, _6y_Lucene40WithOrds_0.tii, _7f.fdt, _7f_nrm.cfe, _6y_Lucene40WithOrds_0.tib,
_7h_Pulsing40_0.frq, _7g.fdt, _7i_Pulsing40_0.prx, _7i_Lucene40WithOrds_0.prx, _6y_1.del,
_6y_Lucene40WithOrds_0.frq, _7g_Pulsing40_0.prx, _7i_Pulsing40_0.frq, _7h.fdx, _6y.fdt, _7g_nrm.cfs,
_7f_1.del, _7i_nrm.cfs, _7h.fdt, _7g.fdx, _6y.fdx, _7f_SimpleText_0.pst, _7g.si, _7f_Lucene40WithOrds_0.tib,
_7h_Pulsing40_0.tim, _7h_Pulsing40_0.prx, _6y_Lucene40WithOrds_0.prx, _7i.fnm, _7f_Lucene40WithOrds_0.frq,
_7g_nrm.cfe, _7f_Lucene40WithOrds_0.tii, _7i_nrm.cfe, segments_6, _7f_Pulsing40_0.tim, _7f_Pulsing40_0.tip]
[junit4:junit4]   2> 37005 T132 C6 P18803 oasc.SolrDeletionPolicy.updateCommits newest
commit = 6
[junit4:junit4]   2> 37044 T132 C6 P18803 oass.SolrIndexSearcher.<init> Opening Searcher@18cba1b4
main
[junit4:junit4]   2> 37045 T132 C6 P18803 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 37046 T150 oasc.SolrCore.registerSearcher [collection1] Registered
new searcher Searcher@18cba1b4 main{StandardDirectoryReader(segments_6:577:nrt _6y(4.0):C1106/57
_7f(4.0):C128/14 _7g(4.0):C10 _7h(4.0):C10 _7i(4.0):C9)}
[junit4:junit4]   2> 37047 T132 C6 P18803 /update {waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2}
{commit=} 0 298
[junit4:junit4]   2> 37047 T58 C5 P11765 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2}
{commit=} 0 680
[junit4:junit4]   2> 37056 T57 C5 P11765 REQ /select {distrib=false&wt=javabin&q=*:*&version=2}
hits=1192 status=0 QTime=4 
[junit4:junit4]   2> 37066 T144 C6 P18803 REQ /select {distrib=false&wt=javabin&q=*:*&version=2}
hits=1192 status=0 QTime=2 
[junit4:junit4]   2> 37072 T57 C5 P11765 REQ /select {distrib=false&wt=javabin&q=*:*&version=2}
hits=1192 status=0 QTime=0 
[junit4:junit4]   2> 37077 T144 C6 P18803 REQ /select {distrib=false&wt=javabin&q=*:*&version=2}
hits=1192 status=0 QTime=0 
[junit4:junit4]   2> 37079 T30 oasc.CoreContainer.shutdown Shutting down CoreContainer
instance=1223205528
[junit4:junit4]   2> 37079 T30 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@35427e6e
[junit4:junit4]   2> 37082 T30 oasc.SolrCore.closeSearcher [collection1] Closing main searcher
on request.
[junit4:junit4]   2> 37083 T30 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=2345,cumulative_deletesById=1153,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 37085 T35 oazs.PrepRequestProcessor.pRequest Processed session termination
for sessionid: 0x138750b6d200002
[junit4:junit4]   2> 37086 T51 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 37086 T81 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 37086 T30 oaz.ZooKeeper.close Session: 0x138750b6d200002 closed
[junit4:junit4]   2> 37087 T51 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered,
but Solr cannot talk to ZK
[junit4:junit4]   2> 37086 T126 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 37086 T85 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 37087 T51 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 37087 T32 oazs.NIOServerCnxn.closeSock Closed socket connection for
client /140.211.11.196:41543 which had sessionid 0x138750b6d200002
[junit4:junit4]   2> 37088 T30 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 37089 T65 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 37092 T65 oasc.Overseer.<init> Overseer (id=87948884669628419-127.0.0.1:11765_solr-n_0000000001)
starting
[junit4:junit4]   2> 37092 T35 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException
when processing sessionid:0x138750b6d200003 type:create cxid:0x61 zxid:0xfffffffffffffffe
txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for
/solr/overseer
[junit4:junit4]   2> 37102 T35 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException
when processing sessionid:0x138750b6d200003 type:create cxid:0x62 zxid:0xfffffffffffffffe
txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for
/solr/overseer
[junit4:junit4]   2> 37104 T35 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException
when processing sessionid:0x138750b6d200003 type:create cxid:0x63 zxid:0xfffffffffffffffe
txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for
/solr/overseer
[junit4:junit4]   2> 37106 T35 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException
when processing sessionid:0x138750b6d200003 type:create cxid:0x64 zxid:0xfffffffffffffffe
txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for
/solr/overseer
[junit4:junit4]   2> 37112 T65 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 37112 T237 oasc.OverseerCollectionProcessor.run Process current queue
of collection creations
[junit4:junit4]   2> 37112 T236 oasc.Overseer$CloudStateUpdater.run Starting to work on
the main queue
[junit4:junit4]   2> 37140 T30 oasc.CoreContainer.shutdown Shutting down CoreContainer
instance=1136560032
[junit4:junit4]   2> 37140 T30 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@3f4de7ea
[junit4:junit4]   2> 37144 T30 oasc.SolrCore.closeSearcher [collection1] Closing main searcher
on request.
[junit4:junit4]   2> 37146 T30 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=2345,cumulative_deletesById=1153,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 37147 T35 oazs.PrepRequestProcessor.pRequest Processed session termination
for sessionid: 0x138750b6d200003
[junit4:junit4]   2> 37149 T65 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 37149 T85 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 37149 T32 oazs.NIOServerCnxn.closeSock Closed socket connection for
client /140.211.11.196:10220 which had sessionid 0x138750b6d200003
[junit4:junit4]   2> 37149 T81 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 37149 T65 oascc.ZkStateReader$3.process WARNING ZooKeeper watch triggered,
but Solr cannot talk to ZK
[junit4:junit4]   2> 37149 T30 oaz.ZooKeeper.close Session: 0x138750b6d200003 closed
[junit4:junit4]   2> 37150 T65 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 37151 T30 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 37152 T126 oasu.PeerSync.sync PeerSync: core=collection1 url=127.0.0.1:59697/solr
START replicas=[] nUpdates=100
[junit4:junit4]   2> 37161 T126 oasu.PeerSync.sync PeerSync: core=collection1 url=127.0.0.1:59697/solr
DONE. sync succeeded
[junit4:junit4]   2> 37161 T126 oasc.SyncStrategy.syncToMe try and ask http://127.0.0.1:11765/solr/collection1/
to sync
[junit4:junit4]   2> 37164 T140 C6 P18803 oasu.PeerSync.sync PeerSync: core=collection1
url=127.0.0.1:59697/solr START replicas=[127.0.0.1:18803/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 37178 T142 C6 P18803 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100}
status=0 QTime=13 
[junit4:junit4]   2> 37179 T140 C6 P18803 oasu.PeerSync.handleVersions PeerSync: core=collection1
url=127.0.0.1:59697/solr  Received 100 versions from 127.0.0.1:18803/solr/collection1/
[junit4:junit4]   2> 37180 T140 C6 P18803 oasu.PeerSync.handleVersions PeerSync: core=collection1
url=127.0.0.1:59697/solr  Our versions are newer. ourLowThreshold=1407182191558918144 otherHigh=1407182192420847616
[junit4:junit4]   2> 37180 T140 C6 P18803 oasu.PeerSync.sync PeerSync: core=collection1
url=127.0.0.1:59697/solr DONE. sync succeeded
[junit4:junit4]   2> 37180 T140 C6 P18803 REQ /get {sync=127.0.0.1:18803/solr/collection1/&getVersions=100&distrib=false&qt=/get&wt=javabin&version=2}
status=0 QTime=17 
[junit4:junit4]   2> 37181 T126 oasc.RecoveryStrategy.close WARNING Stopping recovery for
core collection1 zkNodeName=127.0.0.1:18803_solr_collection1
[junit4:junit4]   2> 37183 T126 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 37187 T35 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException
when processing sessionid:0x138750b6d200007 type:create cxid:0x4d zxid:0xfffffffffffffffe
txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for
/solr/overseer
[junit4:junit4]   2> 37198 T126 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 37201 T126 oasc.Overseer.<init> Overseer (id=87948884669628423-127.0.0.1:18803_solr-n_0000000004)
starting
[junit4:junit4]   2> 37201 T35 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException
when processing sessionid:0x138750b6d200007 type:create cxid:0x54 zxid:0xfffffffffffffffe
txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for
/solr/overseer
[junit4:junit4]   2> 37203 T30 oasc.CoreContainer.shutdown Shutting down CoreContainer
instance=1820539437
[junit4:junit4]   2> 37203 T35 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException
when processing sessionid:0x138750b6d200007 type:create cxid:0x55 zxid:0xfffffffffffffffe
txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for
/solr/overseer
[junit4:junit4]   2> 37203 T30 oasc.RecoveryStrategy.close WARNING Stopping recovery for
core collection1 zkNodeName=127.0.0.1:18803_solr_collection1
[junit4:junit4]   2> 37203 T30 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1621d02f
[junit4:junit4]   2> 37207 T30 oasc.SolrCore.closeSearcher [collection1] Closing main searcher
on request.
[junit4:junit4]   2> 37222 T30 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=6,autocommits=0,soft
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=1695,cumulative_deletesById=853,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 37222 T35 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException
when processing sessionid:0x138750b6d200007 type:create cxid:0x56 zxid:0xfffffffffffffffe
txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for
/solr/overseer
[junit4:junit4]   2> 37223 T30 oasc.RecoveryStrategy.close WARNING Stopping recovery for
core collection1 zkNodeName=127.0.0.1:18803_solr_collection1
[junit4:junit4]   2> 37224 T35 oazs.PrepRequestProcessor.pRequest Processed session termination
for sessionid: 0x138750b6d200007
[junit4:junit4]   2> 37225 T85 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 37225 T81 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 37225 T32 oazs.NIOServerCnxn.closeSock Closed socket connection for
client /140.211.11.196:20959 which had sessionid 0x138750b6d200007
[junit4:junit4]   2> 37225 T30 oaz.ZooKeeper.close Session: 0x138750b6d200007 closed
[junit4:junit4]   2> 37226 T30 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 37282 T35 oazs.PrepRequestProcessor.pRequest Processed session termination
for sessionid: 0x138750b6d200006
[junit4:junit4]   2> 37283 T85 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 37283 T32 oazs.NIOServerCnxn.closeSock Closed socket connection for
client /140.211.11.196:59085 which had sessionid 0x138750b6d200006
[junit4:junit4]   2> 37283 T30 oaz.ZooKeeper.close Session: 0x138750b6d200006 closed
[junit4:junit4]   2> 37284 T35 oazs.PrepRequestProcessor.pRequest Processed session termination
for sessionid: 0x138750b6d200005
[junit4:junit4]   2> 37286 T81 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 37286 T30 oaz.ZooKeeper.close Session: 0x138750b6d200005 closed
[junit4:junit4]   2> 37286 T32 oazs.NIOServerCnxn.closeSock Closed socket connection for
client /140.211.11.196:10876 which had sessionid 0x138750b6d200005
[junit4:junit4]   2> 37286 T34 oazs.SyncRequestProcessor.run SyncRequestProcessor exited!
[junit4:junit4]   2> 37286 T35 oazs.PrepRequestProcessor.run PrepRequestProcessor exited
loop!
[junit4:junit4]   2> 37287 T30 oazs.FinalRequestProcessor.shutdown shutdown of request
processor complete
[junit4:junit4]   2> 37478 T32 oazs.NIOServerCnxn$Factory.run NIOServerCnxn factory exited
run method
[junit4:junit4]   2> 37479 T30 oazs.FinalRequestProcessor.shutdown shutdown of request
processor complete
[junit4:junit4]   2> 37523 T52 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:679)
[junit4:junit4]   2> 
[junit4:junit4]   2> 37523 T30 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4:junit4]   2> 37527 T52 oasc.Overseer$CloudStateUpdater.amILeader According to ZK
I (id=87948884669628418-127.0.0.1:58462_solr-n_0000000000) am no longer a leader.
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=RecoveryZkTest -Dtests.method=testDistribSearch
-Dtests.seed=18FCBFE19AC2B6AC -Dtests.slow=true -Dtests.locale=is_IS -Dtests.timezone=Atlantic/St_Helena
-Dtests.file.encoding=ISO-8859-1
[junit4:junit4]   2>
[junit4:junit4]    > (@AfterClass output)
[junit4:junit4]   2> 37584 T30 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40: {range_facet_l=PostingsFormat(name=Memory
doPackFST= false), text=PostingsFormat(name=SimpleText), _version_=PostingsFormat(name=Memory
doPackFST= false), rnd_b=Pulsing40(freqCutoff=7 minBlockSize=8 maxBlockSize=37), intDefault=PostingsFormat(name=Memory
doPackFST= false), id=Pulsing40(freqCutoff=7 minBlockSize=8 maxBlockSize=37), timestamp=PostingsFormat(name=Memory
doPackFST= false), a_t=PostingsFormat(name=Memory doPackFST= false), range_facet_sl=PostingsFormat(name=SimpleText),
range_facet_si=PostingsFormat(name=Lucene40WithOrds), other_tl1=PostingsFormat(name=Memory
doPackFST= false), multiDefault=PostingsFormat(name=Lucene40WithOrds), a_si=PostingsFormat(name=Lucene40WithOrds)},
sim=DefaultSimilarity, locale=is_IS, timezone=Atlantic/St_Helena
[junit4:junit4]   2> NOTE: FreeBSD 9.0-RELEASE amd64/Sun Microsystems Inc. 1.6.0_32 (64-bit)/cpus=16,threads=11,free=46034272,total=248774656
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestSpanishLightStemFilterFactory,
HighlighterConfigTest, HighlighterTest, IndexBasedSpellCheckerTest, TestPortugueseMinimalStemFilterFactory,
TestIndonesianStemFilterFactory, TestStemmerOverrideFilterFactory, RecoveryZkTest]
[junit4:junit4]   2> 
[junit4:junit4] Completed in 37.61s, 1 test, 1 error <<< FAILURES!
[...truncated 913 lines...]

[...truncated 50084 lines...]

[...truncated 50084 lines...]

[...truncated 50084 lines...]

[...truncated 50084 lines...]

[...truncated 50065 lines...]
BUILD FAILED
/usr/home/hudson/hudson-slave/workspace/Solr-4.x/checkout/solr/build.xml:144: The following
error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Solr-4.x/checkout/lucene/module-build.xml:62: The
following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Solr-4.x/checkout/lucene/common-build.xml:1095: The
following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Solr-4.x/checkout/lucene/common-build.xml:792: There
were test failures: 299 suites, 1062 tests, 1 error, 5 ignored

Total time: 49 minutes 36 seconds
Build step 'Execute shell' marked build as failure
[TASKS] Scanning folder '/home/hudson/hudson-slave/workspace/Solr-4.x' for files matching
the pattern '**/*.java' - excludes: 
[TASKS] Found 3879 files to scan for tasks
[TASKS] Found 1345 open tasks.
[TASKS] Computing warning deltas based on reference build #33
Archiving artifacts
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure
Recording test results
Publishing Javadoc
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message