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