Build: https://builds.apache.org/job/Lucene-Solr-tests-only-trunk/14941/
1 tests failed.
REGRESSION: org.apache.solr.cloud.RecoveryZkTest.testDistribSearch
Error Message:
expected:<2134> but was:<2131>
Stack Trace:
java.lang.AssertionError: expected:<2134> but was:<2131>
at
__randomizedtesting.SeedInfo.seed([464A264BD38912FE:C7ACA853A4D672C2]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.cloud.RecoveryZkTest.doTest(RecoveryZkTest.java:107)
at
org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:679)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:616)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1995)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.access$1100(RandomizedRunner.java:132)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:818)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:877)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:891)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:32)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at
org.apache.lucene.util.TestRuleReportUncaughtExceptions$1.evaluate(TestRuleReportUncaughtExceptions.java:68)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:825)
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)
Build Log:
[...truncated 35962 lines...]
[junit4:junit4] 2> 37397 T1857 C151 P26525 /update {wt=javabin&version=2}
{add=[2269 (1408484678163234816)]} 0 0
[junit4:junit4] 2> 37398 T3647 C153 P62559 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0
[junit4:junit4] 2> 37398 T1871 C152 P55793 /update {wt=javabin&version=2}
{add=[11855 (1408484678162186240)]} 0 2
[junit4:junit4] 2> 37401 T1857 C151 P26525 /update {wt=javabin&version=2}
{delete=[11789 (-1408484678167429120)]} 0 0
[junit4:junit4] 2> 37401 T3647 C153 P62559 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0
[junit4:junit4] 2> 37401 T1872 C152 P55793 /update {wt=javabin&version=2}
{add=[2269 (1408484678165331968)]} 0 2
[junit4:junit4] 2> 37404 T1857 C151 P26525 /update {wt=javabin&version=2}
{add=[2270 (1408484678170574848)]} 0 0
[junit4:junit4] 2> 37405 T3647 C153 P62559 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0
[junit4:junit4] 2> 37405 T1871 C152 P55793 /update {wt=javabin&version=2}
{delete=[11789 (-1408484678169526272)]} 0 2
[junit4:junit4] 2> 37407 T1857 C151 P26525 /update {wt=javabin&version=2}
{add=[11856 (1408484678173720576)]} 0 0
[junit4:junit4] 2> 37408 T3647 C153 P62559 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0
[junit4:junit4] 2> 37408 T1872 C152 P55793 /update {wt=javabin&version=2}
{add=[2270 (1408484678172672000)]} 0 2
[junit4:junit4] 2> 37410 T1857 C151 P26525 /update {wt=javabin&version=2}
{delete=[2177 (-1408484678176866304)]} 0 0
[junit4:junit4] 2> 37411 T3647 C153 P62559 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0
[junit4:junit4] 2> 37411 T1871 C152 P55793 /update {wt=javabin&version=2}
{add=[11856 (1408484678175817728)]} 0 2
[junit4:junit4] 2> 37413 T1857 C151 P26525 /update {wt=javabin&version=2}
{add=[11857 (1408484678180012032)]} 0 0
[junit4:junit4] 2> 37414 T3647 C153 P62559 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0
[junit4:junit4] 2> 37414 T1872 C152 P55793 /update {wt=javabin&version=2}
{delete=[2177 (-1408484678178963456)]} 0 2
[junit4:junit4] 2> 37417 T1857 C151 P26525 /update {wt=javabin&version=2}
{add=[2271 (1408484678184206336)]} 0 0
[junit4:junit4] 2> 37419 T3647 C153 P62559 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2
[junit4:junit4] 2> 37420 T1871 C152 P55793 /update {wt=javabin&version=2}
{add=[11857 (1408484678182109184)]} 0 5
[junit4:junit4] 2> 37421 T3622 C153 P62559 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0
[junit4:junit4] 2> 37422 T1872 C152 P55793 /update {wt=javabin&version=2}
{add=[2271 (1408484678186303488)]} 0 3
[junit4:junit4] 2> 37422 T1857 C151 P26525 /update {wt=javabin&version=2}
{add=[11858 (1408484678189449216)]} 0 0
[junit4:junit4] 2> 37424 T1856 C151 P26525 /update {wt=javabin&version=2}
{delete=[2178 (-1408484678191546368)]} 0 0
[junit4:junit4] 2> 37426 T3622 C153 P62559 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0
[junit4:junit4] 2> 37426 T1872 C152 P55793 /update {wt=javabin&version=2}
{add=[11858 (1408484678191546368)]} 0 2
[junit4:junit4] 2> 37428 T3647 C153 P62559 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0
[junit4:junit4] 2> 37429 T1871 C152 P55793 /update {wt=javabin&version=2}
{delete=[2178 (-1408484678193643520)]} 0 3
[junit4:junit4] 2> 37429 T1856 C151 P26525 /update {wt=javabin&version=2}
{add=[11859 (1408484678195740672)]} 0 1
[junit4:junit4] 2> 37431 T1857 C151 P26525 /update {wt=javabin&version=2}
{add=[2272 (1408484678198886400)]} 0 0
[junit4:junit4] 2> 37431 T1844 oascc.ZkStateReader.updateCloudState Updating
cloud state from ZooKeeper...
[junit4:junit4] 2> 37433 T3647 C153 P62559 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0
[junit4:junit4] 2> 37434 T1871 C152 P55793 /update {wt=javabin&version=2}
{add=[11859 (1408484678198886400)]} 0 3
[junit4:junit4] 2> added docs:1859 with 26 fails deletes:900
[junit4:junit4] 2> 37437 T3647 C153 P62559 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0
[junit4:junit4] 2> 37438 T1872 C152 P55793 /update {wt=javabin&version=2}
{add=[2272 (1408484678202032128)]} 0 4
[junit4:junit4] 2> added docs:2272 with 36 fails deletes:1097
[junit4:junit4] 2> 37440 T1857 C151 P26525 oasu.DirectUpdateHandler2.commit
start
commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4] 2> 37893 T1857 C151 P26525 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/Lucene-Solr-tests-only-trunk/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1343235625223/control/data/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@72e46ea8;
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/Lucene-Solr-tests-only-trunk/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1343235625223/control/data/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@72e46ea8;
maxCacheMB=48.0
maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_d0.frq, _6g.tip,
_c9.fnm, _6g.tim, _d0.fnm, _cy.fdt, _c9.fdx, _c9.frq, _cy.prx, _6g.fnm,
_cz.tip, _6g.frq, _cz.tim, _cy.tim, _6g.prx, _cy.fnm, _d1.tim, _c9.prx,
_d1_nrm.cfe, _d1.tip, _d0.fdt, _cy.frq, _cz_nrm.cfs, _cy_nrm.cfs, _d0.si,
_d0.fdx, _6g.si, _cz.fnm, _d1_nrm.cfs, _d3.frq, _d3.fnm, _c9.fdt, _d3.prx,
_c9_nrm.cfs, _cy.fdx, _d0.prx, _d2.tip, _cy_nrm.cfe, _c9_nrm.cfe, _d1.prx,
_cz.frq, _d3_nrm.cfe, _d3.si, _d0_nrm.cfs, _cz.fdx, _d2.tim, _d2.si,
_d3_nrm.cfs, _c9_1.del, _cz.si, _d2.fnm, _d3.tip, _d0.tip, _d0_nrm.cfe,
_d3.tim, _d1.si, _cy.tip, _d0.tim, _d2.frq, _6g.fdt, _6g.fdx, _6g_nrm.cfe,
_c9.tim, _c9.si, _d1.fdx, _cz_nrm.cfe, _d1.frq, _d2.prx, _d1.fdt, _d2_nrm.cfs,
_6g_1.del, _d2.fdt, _6g_nrm.cfs, _d3.fdx, _cz.fdt, _d3.fdt, _cz.prx,
segments_2, _cz_1.del, _cy.si, _d2_nrm.cfe, _d1.fnm, _d2.fdx, _c9.tip]
[junit4:junit4] 2> 37893 T1857 C151 P26525
oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4] 2> 37905 T1857 C151 P26525 oass.SolrIndexSearcher.<init>
Opening Searcher@414b8fd3 main
[junit4:junit4] 2> 37906 T1857 C151 P26525 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4:junit4] 2> 37906 T1867 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@414b8fd3
main{StandardDirectoryReader(segments_2:1003 _6g(5.0):C1015/22
_c9(5.0):C1006/73 _cz(5.0):C183/16 _cy(5.0):C10 _d0(5.0):C10 _d1(5.0):C10
_d2(5.0):C10 _d3(5.0):C1)}
[junit4:junit4] 2> 37907 T1857 C151 P26525 /update
{waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=}
0 467
[junit4:junit4] 2> 37910 T1872 C152 P55793 oasu.DirectUpdateHandler2.commit
start
commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4] 2> 38180 T1872 C152 P55793 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/Lucene-Solr-tests-only-trunk/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1343235625223/jetty1/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5c22bd75;
maxCacheMB=48.0
maxMergeSizeMB=4.0),segFN=segments_3,generation=3,filenames=[_84.si,
_85_nrm.cfs, _86.fdx, _85.prx, _86.fdt, _82_nrm.cfe, _82.prx, _86_nrm.cfe,
_86.si, _6h_nrm.cfe, _6h.si, _85.fdx, _82_1.del, _84.fdx, _84.fdt, _81_nrm.cfs,
_81.prx, _82.frq, _84_nrm.cfe, _85.fdt, _83_nrm.cfs, _86.prx, _82.fnm,
_6h_nrm.cfs, _81.frq, _85.tim, _84.frq, _84.tip, _82.fdt, _85.tip, _84.tim,
_81.fnm, _82.fdx, _81.fdt, _6h_1.del, _84.fnm, _81.tim, _6h.fnm, _81.tip,
_81.fdx, _85.frq, _83.si, _86.fnm, _85.fnm, _86.frq, _81_nrm.cfe, _6h.frq,
_6h.fdt, _6h.tim, _84_nrm.cfs, _6h.fdx, _83_nrm.cfe, _83.tip, _6h.tip, _83.tim,
_84.prx, _85.si, _86_nrm.cfs, _82_nrm.cfs, _83.fnm, _86.tim, _82.tip, _82.si,
_82.tim, _81.si, _6h.prx, _83.frq, _83.fdt, segments_3, _85_nrm.cfe, _86.tip,
_83.prx, _83.fdx]
[junit4:junit4] 2>
commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1343235625223/jetty1/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5c22bd75;
maxCacheMB=48.0
maxMergeSizeMB=4.0),segFN=segments_4,generation=4,filenames=[_89.frq, _89.fnm,
_cb.fnm, _cb.prx, _cc_nrm.cfe, _ca.fdt, _ca.tim, _ce_nrm.cfs, _ca.fdx,
_cb_nrm.cfe, _ca_1.del, _89_nrm.cfs, _89.prx, _ca.tip, _cb.si, _ca.frq,
_ce.frq, _cc_nrm.cfs, _cb.fdx, _cd.si, _ce_nrm.cfe, _cc.tim, _ce.fdt, _ca.fnm,
_cb.fdt, _cb_nrm.cfs, _cc.tip, _c8.prx, _ce.fdx, _ce.fnm, _89_1.del,
_c8_nrm.cfs, _c8.tip, _ce.prx, _cc.prx, _c8_nrm.cfe, _cc.fdt, _cc.si,
_cd_nrm.cfe, _89.si, _c8.tim, _ca_nrm.cfs, _cd.frq, _c8.fdt, _cc.fdx, _cd.prx,
_cd.fnm, _ce.si, _cd.fdx, _cd.fdt, _89.fdt, _cc.fnm, _89_nrm.cfe, _89.fdx,
_cb.tim, _ce.tim, _c8.fnm, _cc.frq, _cb.tip, _ce.tip, _cb.frq, _c8.frq,
_c8.fdx, _cd_nrm.cfs, _ca.si, _ca.prx, _c8.si, _89.tim, _ca_nrm.cfe, _cd.tip,
_cd.tim, _89.tip, segments_4]
[junit4:junit4] 2>
commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1343235625223/jetty1/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5c22bd75;
maxCacheMB=48.0
maxMergeSizeMB=4.0),segFN=segments_5,generation=5,filenames=[_89.frq, _d7.fdx,
_d7.prx, _d7.fdt, _d7_nrm.cfs, _89.si, _d7_nrm.cfe, _d7.fnm, _89.fnm, _d6.fdt,
_d5.fnm, _d6.fdx, _d5.tip, _d6.prx, _d5.frq, _d5.tim, _89.prx, _89_nrm.cfs,
_d6.si, _d7.tip, _d7.tim, _89.fdt, _89.fdx, _89_nrm.cfe, _d5_nrm.cfe, _d5.fdt,
_d6_nrm.cfs, _d5.fdx, _d6.fnm, _89_1.del, _d5.si, _d7.frq, _d5_1.del, _d5.prx,
_d6.tim, _89.tim, _d5_nrm.cfs, _d6.frq, _d7.si, _d6.tip, segments_5, _89.tip,
_d6_nrm.cfe]
[junit4:junit4] 2> 38223 T1872 C152 P55793
oasc.SolrDeletionPolicy.updateCommits newest commit = 5
[junit4:junit4] 2> 38240 T1872 C152 P55793 oass.SolrIndexSearcher.<init>
Opening Searcher@263d1f2a main
[junit4:junit4] 2> 38241 T1879 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@263d1f2a
main{StandardDirectoryReader(segments_5:1016 _89(5.0):C1302/24 _d5(5.0):C848/3
_d7(5.0):C6 _d6(5.0):C5)}
[junit4:junit4] 2> 38241 T1872 C152 P55793 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4:junit4] 2> 38245 T3647 C153 P62559 oasu.DirectUpdateHandler2.commit
start
commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4] 2> 38327 T3647 C153 P62559 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/Lucene-Solr-tests-only-trunk/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1343235625223/jetty2/index.20120726000101462
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@29a430a0;
maxCacheMB=48.0
maxMergeSizeMB=4.0),segFN=segments_5,generation=5,filenames=[_89.frq, _cu.fnm,
_cu_nrm.cfe, _cw_nrm.cfs, _cw.frq, _ct_nrm.cfs, _cu.frq, _89.fnm, _cw_nrm.cfe,
_cw.fnm, _ct.fnm, _cr.frq, _cu.tim, _cr.fdt, _ct_nrm.cfe, _ct.frq, _cu.tip,
_cs.fnm, _cr.prx, _cu_nrm.cfs, _ct.prx, _89.prx, _89_nrm.cfs, _cs.frq, _cr.fdx,
_cv.fdx, _cw.prx, _cv.fdt, _cv.si, _ct.tim, _89_1.del, _ct.tip, _cw.tip,
_cw.tim, _cv_nrm.cfs, _cr_nrm.cfs, _89.si, _cw.fdt, _cr_1.del, _cw.fdx,
_cv_nrm.cfe, _cv.tip, _cv.tim, _cs_nrm.cfs, _cw.si, _cs.tip, _cr_nrm.cfe,
_cs.fdt, _cr.fnm, _cs.tim, _cs_nrm.cfe, _cv.frq, _cs.fdx, _cv.fnm, _89.fdt,
_ct.fdt, _cu.fdt, _89.fdx, _89_nrm.cfe, _cu.fdx, _ct.fdx, _ct.si, _cv.prx,
_cs.si, _cr.tim, _cs.prx, _cr.tip, _cr.si, _89.tim, _cu.si, _cu.prx,
segments_5, _89.tip]
[junit4:junit4] 2>
commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1343235625223/jetty2/index.20120726000101462
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@29a430a0;
maxCacheMB=48.0
maxMergeSizeMB=4.0),segFN=segments_6,generation=6,filenames=[_89.frq, _d7.fdx,
_d8.frq, _d8.tim, _d7.prx, _d7.fdt, _89.si, _d7_nrm.cfs, _89.fnm, _d7.fnm,
_d7_nrm.cfe, _d8.fnm, _d8.si, _89_nrm.cfs, _89.prx, _d7.tip, _d8.prx, _89.fdt,
_d7.tim, _89_nrm.cfe, _89.fdx, _d7_1.del, _89_1.del, _d8_nrm.cfs, _d7.frq,
_89.tim, _d8.fdt, _d7.si, _d8.tip, _d8_nrm.cfe, segments_6, _d8.fdx, _89.tip]
[junit4:junit4] 2> 38327 T3647 C153 P62559
oasc.SolrDeletionPolicy.updateCommits newest commit = 6
[junit4:junit4] 2> 38335 T3647 C153 P62559 oass.SolrIndexSearcher.<init>
Opening Searcher@4a191c9 main
[junit4:junit4] 2> 38336 T3647 C153 P62559 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4:junit4] 2> 38336 T3714 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@4a191c9
main{StandardDirectoryReader(segments_6:1017:nrt _89(5.0):C1302/24
_d7(5.0):C850/1 _d8(5.0):C4)}
[junit4:junit4] 2> 38337 T3647 C153 P62559 /update
{waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2}
{commit=} 0 92
[junit4:junit4] 2> 38337 T1872 C152 P55793 /update
{waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=}
0 427
[junit4:junit4] 2> 38339 T1870 C152 P55793 REQ /select
{distrib=false&wt=javabin&q=*:*&version=2} hits=2134 status=0 QTime=1
[junit4:junit4] 2> 38341 T3680 C153 P62559 REQ /select
{distrib=false&wt=javabin&q=*:*&version=2} hits=2131 status=0 QTime=1
[junit4:junit4] 2> 38343 T1870 C152 P55793 REQ /select
{distrib=false&wt=javabin&q=*:*&version=2} hits=2134 status=0 QTime=0
[junit4:junit4] 2> 38345 T3680 C153 P62559 REQ /select
{distrib=false&wt=javabin&q=*:*&version=2} hits=2131 status=0 QTime=0
[junit4:junit4] 2> 38346 T1849 oazs.PrepRequestProcessor.pRequest Processed
session termination for sessionid: 0x138bf15110c0006
[junit4:junit4] 2> 38347 T1898 oaz.ClientCnxn$EventThread.run EventThread
shut down
[junit4:junit4] 2> 38347 T1846 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /140.211.11.196:31128 which had sessionid
0x138bf15110c0006
[junit4:junit4] 2> 38347 T1844 oaz.ZooKeeper.close Session: 0x138bf15110c0006
closed
[junit4:junit4] 2> 38350 T1849 oazs.PrepRequestProcessor.pRequest Processed
session termination for sessionid: 0x138bf15110c0005
[junit4:junit4] 2> 38351 T1894 oaz.ClientCnxn$EventThread.run EventThread
shut down
[junit4:junit4] 2> 38351 T1846 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /140.211.11.196:58254 which had sessionid
0x138bf15110c0005
[junit4:junit4] 2> 38351 T1844 oaz.ZooKeeper.close Session: 0x138bf15110c0005
closed
[junit4:junit4] 2> 38352 T1849 oazs.PrepRequestProcessor.run
PrepRequestProcessor exited loop!
[junit4:junit4] 2> 38352 T1848 oazs.SyncRequestProcessor.run
SyncRequestProcessor exited!
[junit4:junit4] 2> 38353 T1844 oazs.FinalRequestProcessor.shutdown shutdown
of request processor complete
[junit4:junit4] 2> 38415 T1844 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /140.211.11.196:36982 which had sessionid
0x138bf15110c0003
[junit4:junit4] 2> 38415 T1844 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /140.211.11.196:13596 which had sessionid
0x138bf15110c0002
[junit4:junit4] 2> 38416 T1877 oaz.ClientCnxn$SendThread.run Unable to read
additional data from server sessionid 0x138bf15110c0003, likely server has
closed socket, closing socket connection and attempting reconnect
[junit4:junit4] 2> 38416 T1863 oaz.ClientCnxn$SendThread.run Unable to read
additional data from server sessionid 0x138bf15110c0002, likely server has
closed socket, closing socket connection and attempting reconnect
[junit4:junit4] 2> 38416 T1844 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /140.211.11.196:54705 which had sessionid
0x138bf15110c0007
[junit4:junit4] 2> 38416 T3628 oaz.ClientCnxn$SendThread.run Unable to read
additional data from server sessionid 0x138bf15110c0007, likely server has
closed socket, closing socket connection and attempting reconnect
[junit4:junit4] 2> 38416 T1846 oazs.NIOServerCnxn$Factory.run NIOServerCnxn
factory exited run method
[junit4:junit4] 2> 38417 T1844 oazs.FinalRequestProcessor.shutdown shutdown
of request processor complete
[junit4:junit4] 2> 38417 T1844 oasc.ChaosMonkey.monkeyLog monkey: stop shard!
26525
[junit4:junit4] 2> 38417 T1844 oasc.CoreContainer.shutdown Shutting down
CoreContainer instance=440684931
[junit4:junit4] 2> 38417 T1844 oasc.SolrCore.close [collection1] CLOSING
SolrCore org.apache.solr.core.SolrCore@74aaadcc
[junit4:junit4] 2> 38418 T1844 oasc.SolrCore.closeSearcher [collection1]
Closing main searcher on request.
[junit4:junit4] 2> 38419 T1844 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=4131,cumulative_deletesById=1997,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4] 2> 38457 T1844 C151 P26525 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/Lucene-Solr-tests-only-trunk/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1343235625223/control/data/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@72e46ea8;
maxCacheMB=48.0
maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_d0.frq, _6g.tip,
_c9.fnm, _6g.tim, _d0.fnm, _cy.fdt, _c9.fdx, _c9.frq, _cy.prx, _6g.fnm,
_cz.tip, _6g.frq, _cz.tim, _cy.tim, _6g.prx, _cy.fnm, _d1.tim, _c9.prx,
_d1_nrm.cfe, _d1.tip, _d0.fdt, _cy.frq, _cz_nrm.cfs, _cy_nrm.cfs, _d0.si,
_d0.fdx, _6g.si, _cz.fnm, _d1_nrm.cfs, _d3.frq, _d3.fnm, _c9.fdt, _d3.prx,
_c9_nrm.cfs, _cy.fdx, _d0.prx, _d2.tip, _cy_nrm.cfe, _c9_nrm.cfe, _d1.prx,
_cz.frq, _d3_nrm.cfe, _d3.si, _d0_nrm.cfs, _cz.fdx, _d2.tim, _d2.si,
_d3_nrm.cfs, _c9_1.del, _cz.si, _d2.fnm, _d3.tip, _d0.tip, _d0_nrm.cfe,
_d3.tim, _d1.si, _cy.tip, _d0.tim, _d2.frq, _6g.fdt, _6g.fdx, _6g_nrm.cfe,
_c9.tim, _c9.si, _d1.fdx, _cz_nrm.cfe, _d1.frq, _d2.prx, _d1.fdt, _d2_nrm.cfs,
_6g_1.del, _d2.fdt, _6g_nrm.cfs, _d3.fdx, _cz.fdt, _d3.fdt, _cz.prx,
segments_2, _cz_1.del, _cy.si, _d2_nrm.cfe, _d1.fnm, _d2.fdx, _c9.tip]
[junit4:junit4] 2>
commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk/checkout/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-1343235625223/control/data/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@72e46ea8;
maxCacheMB=48.0
maxMergeSizeMB=4.0),segFN=segments_3,generation=3,filenames=[_d4.prx, _d4.tim,
_d4.fdx, _d4.si, _d4.tip, _d4.fnm, _d4_nrm.cfe, _d4_nrm.cfs, segments_3,
_d4.fdt, _d4.frq]
[junit4:junit4] 2> 38457 T1844 C151 P26525
oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4] 2> 38517 T3629 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@6ad73088
name:ZooKeeperConnection Watcher:127.0.0.1:56293/solr got event WatchedEvent
state:Disconnected type:None path:null path:null type:None
[junit4:junit4] 2> 38517 T1878 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@4aa51c9d
name:ZooKeeperConnection Watcher:127.0.0.1:56293/solr got event WatchedEvent
state:Disconnected type:None path:null path:null type:None
[junit4:junit4] 2> 38517 T1864 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@7b437ced
name:ZooKeeperConnection Watcher:127.0.0.1:56293/solr got event WatchedEvent
state:Disconnected type:None path:null path:null type:None
[junit4:junit4] 2> 38517 T1844 oaz.ZooKeeper.close Session: 0x138bf15110c0002
closed
[junit4:junit4] 2> 38517 T1864 oaz.ClientCnxn$EventThread.run EventThread
shut down
[junit4:junit4] 2> 38517 T1866 oasc.OverseerCollectionProcessor.run WARNING
Overseer cannot talk to ZK
[junit4:junit4] 2> 38518 T1844 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> 38574 T1844 oasc.ChaosMonkey.monkeyLog monkey: stop shard!
55793
[junit4:junit4] 2> 38574 T1844 oasc.CoreContainer.shutdown Shutting down
CoreContainer instance=1858535252
[junit4:junit4] 2> 38574 T1844 oasc.SolrCore.close [collection1] CLOSING
SolrCore org.apache.solr.core.SolrCore@63829d01
[junit4:junit4] 2> 38575 T1844 oasc.SolrCore.closeSearcher [collection1]
Closing main searcher on request.
[junit4:junit4] 2> 38576 T1844 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=4131,cumulative_deletesById=1997,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4] 2> 38824 T1865 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> 38824 T1865 oasc.Overseer$CloudStateUpdater.amILeader
According to ZK I (id=88030289934942210-127.0.0.1:26525_solr-n_0000000000) am
no longer a leader.
[junit4:junit4] 2> 39959 T1877 oaz.ClientCnxn$SendThread.startConnect Opening
socket connection to server localhost/127.0.0.1:56293
[junit4:junit4] 2> 40236 T3628 oaz.ClientCnxn$SendThread.startConnect Opening
socket connection to server localhost/127.0.0.1:56293
[junit4:junit4] 2> 40782 T1847 oazs.SessionTrackerImpl.run SessionTrackerImpl
exited loop!
[junit4:junit4] 2> 50518 T3628 oaz.ClientCnxn$SendThread.run Client session
timed out, have not heard from server in 12001ms for sessionid
0x138bf15110c0007, closing socket connection and attempting reconnect
[junit4:junit4] 2> 50619 T1878 oaz.ClientCnxn$EventThread.run EventThread
shut down
[junit4:junit4] 2> 50619 T1844 oaz.ZooKeeper.close Session: 0x138bf15110c0003
closed
[junit4:junit4] 2> 50620 T1844 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> 50672 T1844 oasc.ChaosMonkey.monkeyLog monkey: stop shard!
62559
[junit4:junit4] 2> 50672 T1844 oasc.CoreContainer.shutdown Shutting down
CoreContainer instance=149090166
[junit4:junit4] 2> 50672 T1844 oasc.RecoveryStrategy.close WARNING Stopping
recovery for core collection1 zkNodeName=127.0.0.1:62559_solr_collection1
[junit4:junit4] 2> 50672 T1844 oasc.SolrCore.close [collection1] CLOSING
SolrCore org.apache.solr.core.SolrCore@17c7a8f3
[junit4:junit4] 2> 50673 T1844 oasc.SolrCore.closeSearcher [collection1]
Closing main searcher on request.
[junit4:junit4] 2> 50674 T1844 oasu.DirectUpdateHandler2.close closing
DirectUpdateHandler2{commits=5,autocommits=0,soft
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2286,cumulative_deletesById=1061,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4] 2> 50675 T1844 oasc.RecoveryStrategy.close WARNING Stopping
recovery for core collection1 zkNodeName=127.0.0.1:62559_solr_collection1
[junit4:junit4] 2> 52337 T3628 oaz.ClientCnxn$SendThread.startConnect Opening
socket connection to server localhost/127.0.0.1:56293
[junit4:junit4] 2> 62721 T3629 oaz.ClientCnxn$EventThread.run EventThread
shut down
[junit4:junit4] 2> 62721 T1844 oaz.ZooKeeper.close Session: 0x138bf15110c0007
closed
[junit4:junit4] 2> 62722 T1844 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> 62803 T1844 oas.SolrTestCaseJ4.tearDown ###Ending
testDistribSearch
[junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=RecoveryZkTest
-Dtests.method=testDistribSearch -Dtests.seed=464A264BD38912FE
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ca
-Dtests.timezone=Asia/Bangkok -Dtests.file.encoding=UTF-8
[junit4:junit4] 2>
[junit4:junit4] > (@AfterClass output)
[junit4:junit4] 2> 62838 T1844 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4] 2> NOTE: test params are: codec=Asserting,
sim=RandomSimilarityProvider(queryNorm=true,coord=false): {}, locale=ca,
timezone=Asia/Bangkok
[junit4:junit4] 2> NOTE: FreeBSD 9.0-RELEASE amd64/Sun Microsystems Inc.
1.6.0_32 (64-bit)/cpus=16,threads=64,free=187258648,total=285278208
[junit4:junit4] 2> NOTE: All tests run in this JVM: [DateMathParserTest,
TermsComponentTest, NotRequiredUniqueKeyTest, NoCacheHeaderTest,
TestXIncludeConfig, AnalysisAfterCoreReloadTest, NumericFieldsTest,
UpdateRequestProcessorFactoryTest, DOMUtilTest, TestSuggestSpellingConverter,
TestValueSourceCache, TestReload, JSONWriterTest, TestMultiCoreConfBootstrap,
DirectSolrConnectionTest, TestQuerySenderNoQuery, TestCollationField,
SolrCoreTest, DistributedTermsComponentTest, TestCoreContainer,
BinaryUpdateRequestHandlerTest, FileBasedSpellCheckerTest, SortByFunctionTest,
SuggesterFSTTest, RequestHandlersTest, PreAnalyzedFieldTest,
SolrCmdDistributorTest, TestDistributedGrouping, TestCharFilters,
TestSurroundQueryParser, SolrCoreCheckLockOnStartupTest,
XmlUpdateRequestHandlerTest, ZkSolrClientTest, BadComponentTest,
TestLMDirichletSimilarityFactory, PeerSyncTest, LoggingHandlerTest,
MinimalSchemaTest, TestRecovery, TestNumberUtils, CopyFieldTest, UUIDFieldTest,
TestJmxIntegration, BasicDistributedZkTest, FullSolrCloudDistribCmdsTest,
TestSearchPerf, TestPseudoReturnFields, TestSort, AlternateDirectoryTest,
LeaderElectionIntegrationTest, IndexSchemaRuntimeFieldTest, TestLFUCache,
RecoveryZkTest]
[junit4:junit4] 2>
[junit4:junit4] Completed on J0 in 62.85s, 1 test, 1 failure <<< FAILURES!
[...truncated 413 lines...]
[...truncated 36526 lines...]
[...truncated 36526 lines...]
[...truncated 36526 lines...]
[...truncated 36526 lines...]
[...truncated 36511 lines...]
BUILD FAILED
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk/checkout/build.xml:29:
The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk/checkout/solr/build.xml:144:
The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk/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/checkout/lucene/common-build.xml:1094:
The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-trunk/checkout/lucene/common-build.xml:789:
There were test failures: 222 suites, 896 tests, 1 failure, 5 ignored
Total time: 27 minutes 40 seconds
Build step 'Execute shell' marked build as failure
Archiving artifacts
Recording test results
Publishing Clover coverage report...
No Clover report will be published due to a Build Failure
Email was triggered for: Failure
Sending email for trigger: Failure
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
