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] Lucene-Solr-tests-only-trunk - Build # 14937 - Still Failing
Date Wed, 25 Jul 2012 01:26:22 GMT
Build: https://builds.apache.org/job/Lucene-Solr-tests-only-trunk/14937/

1 tests failed.
REGRESSION:  org.apache.solr.cloud.RecoveryZkTest.testDistribSearch

Error Message:
expected:<1755> but was:<1754>

Stack Trace:
java.lang.AssertionError: expected:<1755> but was:<1754>
	at __randomizedtesting.SeedInfo.seed([37E23162C3EDAAD2:B604BF7AB4B2CAEE]: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 28466 lines...]
[junit4:junit4]   2> 37165 T323 C4 P57143 /update {wt=javabin&version=2} {add=[1687
(1408425144666292224)]} 0 2
[junit4:junit4]   2> 37167 T2030 C5 P57145 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2}
status=0 QTime=0 
[junit4:junit4]   2> 37167 T322 C4 P57143 /update {wt=javabin&version=2} {add=[11686
(1408425144668389376)]} 0 2
[junit4:junit4]   2> 37168 T308 C3 P57137 /update {wt=javabin&version=2} {add=[1688
(1408425144671535104)]} 0 0
[junit4:junit4]   2> 37170 T307 C3 P57137 /update {wt=javabin&version=2} {add=[11687
(1408425144673632256)]} 0 0
[junit4:junit4]   2> 37172 T2030 C5 P57145 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2}
status=0 QTime=0 
[junit4:junit4]   2> 37172 T322 C4 P57143 /update {wt=javabin&version=2} {add=[1688
(1408425144673632256)]} 0 2
[junit4:junit4]   2> 37174 T2025 C5 P57145 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2}
status=0 QTime=0 
[junit4:junit4]   2> 37174 T323 C4 P57143 /update {wt=javabin&version=2} {add=[11687
(1408425144675729408)]} 0 2
[junit4:junit4]   2> 37175 T307 C3 P57137 /update {wt=javabin&version=2} {add=[1689
(1408425144678875136)]} 0 0
[junit4:junit4]   2> 37177 T308 C3 P57137 /update {wt=javabin&version=2} {add=[11688
(1408425144680972288)]} 0 0
[junit4:junit4]   2> 37179 T2025 C5 P57145 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2}
status=0 QTime=0 
[junit4:junit4]   2> 37179 T323 C4 P57143 /update {wt=javabin&version=2} {add=[1689
(1408425144680972288)]} 0 2
[junit4:junit4]   2> 37182 T308 C3 P57137 /update {wt=javabin&version=2} {delete=[1662
(-1408425144686215168)]} 0 0
[junit4:junit4]   2> 37182 T2025 C5 P57145 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2}
status=0 QTime=0 
[junit4:junit4]   2> 37182 T322 C4 P57143 /update {wt=javabin&version=2} {add=[11688
(1408425144683069440)]} 0 3
[junit4:junit4]   2> 37185 T308 C3 P57137 /update {wt=javabin&version=2} {add=[11689
(1408425144689360896)]} 0 0
[junit4:junit4]   2> 37186 T2025 C5 P57145 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2}
status=0 QTime=0 
[junit4:junit4]   2> 37186 T323 C4 P57143 /update {wt=javabin&version=2} {delete=[1662
(-1408425144688312320)]} 0 2
[junit4:junit4]   2> 37188 T308 C3 P57137 /update {wt=javabin&version=2} {add=[1690
(1408425144692506624)]} 0 0
[junit4:junit4]   2> 37190 T2025 C5 P57145 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2}
status=0 QTime=1 
[junit4:junit4]   2> 37190 T322 C4 P57143 /update {wt=javabin&version=2} {add=[11689
(1408425144691458048)]} 0 3
[junit4:junit4]   2> 37192 T308 C3 P57137 /update {wt=javabin&version=2} {delete=[11662
(-1408425144696700928)]} 0 0
[junit4:junit4]   2> 37192 T2025 C5 P57145 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2}
status=0 QTime=0 
[junit4:junit4]   2> 37193 T323 C4 P57143 /update {wt=javabin&version=2} {add=[1690
(1408425144694603776)]} 0 3
[junit4:junit4]   2> 37196 T2025 C5 P57145 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2}
status=0 QTime=0 
[junit4:junit4]   2> 37196 T322 C4 P57143 /update {wt=javabin&version=2} {delete=[11662
(-1408425144698798080)]} 0 2
[junit4:junit4]   2> 37198 T308 C3 P57137 /update {wt=javabin&version=2} {add=[1691
(1408425144699846656)]} 0 3
[junit4:junit4]   2> 37198 T307 C3 P57137 /update {wt=javabin&version=2} {add=[11690
(1408425144702992384)]} 0 0
[junit4:junit4]   2> 37202 T2025 C5 P57145 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2}
status=0 QTime=0 
[junit4:junit4]   2> 37202 T322 C4 P57143 /update {wt=javabin&version=2} {add=[1691
(1408425144705089536)]} 0 2
[junit4:junit4]   2> 37203 T2030 C5 P57145 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2}
status=0 QTime=0 
[junit4:junit4]   2> 37203 T323 C4 P57143 /update {wt=javabin&version=2} {add=[11690
(1408425144706138112)]} 0 2
[junit4:junit4]   2> 37205 T308 C3 P57137 /update {wt=javabin&version=2} {add=[11691
(1408425144710332416)]} 0 0
[junit4:junit4]   2> 37205 T307 C3 P57137 /update {wt=javabin&version=2} {add=[1692
(1408425144710332417)]} 0 0
[junit4:junit4]   2> 37209 T294 oascc.ZkStateReader.updateCloudState Updating cloud state
from ZooKeeper... 
[junit4:junit4]   2> 37210 T2030 C5 P57145 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2}
status=0 QTime=0 
[junit4:junit4]   2> 37211 T2025 C5 P57145 REQ /update {update.distrib=FROMLEADER&wt=javabin&version=2}
status=0 QTime=1 
[junit4:junit4]   2> 37211 T323 C4 P57143 /update {wt=javabin&version=2} {add=[11691
(1408425144713478144)]} 0 3
[junit4:junit4]   2> 37211 T322 C4 P57143 /update {wt=javabin&version=2} {add=[1692
(1408425144713478145)]} 0 3
[junit4:junit4]   2> added docs:1691 with 3 fails deletes:814
[junit4:junit4]   2> added docs:1692 with 4 fails deletes:814
[junit4:junit4]   2> 37213 T307 C3 P57137 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 37301 T307 C3 P57137 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-1343178849890/control/data/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@51f2c447; 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-1343178849890/control/data/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@51f2c447; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_68.fnm,
_68.si, _aq.si, _aq.frq, _ap.fdt, _68_1.del, _ap.fdx, _ap.fnm, _aq.tip, _aq.fnm, _68_nrm.cfs,
_ap.prx, _aq.tim, _ap.frq, _68.fdt, _68.fdx, _68.frq, _68.prx, _ap_nrm.cfs, _aq_nrm.cfs, _ap.si,
_aq.prx, _ap_nrm.cfe, _aq.fdt, _68.tim, segments_2, _ap.tip, _68.tip, _aq.fdx, _aq_nrm.cfe,
_68_nrm.cfe, _ap.tim]
[junit4:junit4]   2> 37302 T307 C3 P57137 oasc.SolrDeletionPolicy.updateCommits newest
commit = 2
[junit4:junit4]   2> 37307 T307 C3 P57137 oass.SolrIndexSearcher.<init> Opening Searcher@3721b0a5
main
[junit4:junit4]   2> 37308 T307 C3 P57137 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 37308 T318 oasc.SolrCore.registerSearcher [collection1] Registered
new searcher Searcher@3721b0a5 main{StandardDirectoryReader(segments_2:824 _68(5.0):C1018/26
_ap(5.0):C760 _aq(5.0):C3)}
[junit4:junit4]   2> 37312 T307 C3 P57137 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2}
{commit=} 0 99
[junit4:junit4]   2> 37314 T322 C4 P57143 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 37448 T322 C4 P57143 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-1343178849890/jetty1/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a0b78dd; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_3,generation=3,filenames=[_5u.frq,
_5w.tim, _5z.fnm, _5w_nrm.cfs, _5w.tip, _5y.si, _5u.tim, _5z.frq, _5x.prx, _5u.tip, _5v.frq,
_5t.tip, _5t.tim, _5u.fdx, _5u_nrm.cfe, _5v.fnm, _5u.fdt, _5u_nrm.cfs, _5w.prx, _5w.fdt, _5z.fdt,
_5x.tim, _5x.tip, _5w.fdx, _5s.frq, _5v.tim, _5x.fdt, _5s.prx, _5t_1.del, _5v.tip, _5x.fdx,
_5x_nrm.cfs, _5y.frq, _5s.tim, _5s.fnm, _5v.fdx, _5w_nrm.cfe, _5s.fdt, _5v.fdt, _5z.fdx, _5s.fdx,
_5x_nrm.cfe, _5s.tip, _5u.fnm, _5z.tim, _5x.si, _5v_nrm.cfs, _5v.prx, _5t_nrm.cfs, _5t.frq,
_5t.fdx, _5s_nrm.cfe, _5y.fnm, _5x.fnm, _5y.prx, _5t.fnm, _5z.tip, _5x.frq, _5t.fdt, _5w.si,
_5z_nrm.cfs, _5s_nrm.cfs, _5v.si, _5t_nrm.cfe, _5w.frq, _5t.prx, _5u.prx, _5y.tip, _5z.prx,
_5u.si, _5y.fdx, _5z_nrm.cfe, _5y_nrm.cfe, _5y.fdt, _5y.tim, _5t.si, _5s.si, _5v_nrm.cfe,
_5y_nrm.cfs, segments_3, _5z.si, _5w.fnm]
[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-1343178849890/jetty1/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a0b78dd; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_4,generation=4,filenames=[_68.si,
_9w.fdx, _9x.tip, _9x.tim, _68_1.del, _9w.fdt, _68_nrm.cfs, _9y_nrm.cfe, _9t.frq, _9u.frq,
_9t.fnm, _9v_nrm.cfe, _9x_nrm.cfs, _9u.fnm, _9v.frq, _9u_nrm.cfe, _9x_nrm.cfe, _9w.frq, _9u_nrm.cfs,
_9v_nrm.cfs, _9t_1.del, _9w.fnm, _9y_nrm.cfs, _9x.si, _68.fnm, _9v.fdx, _9t.si, _9w.si, _9t_nrm.cfs,
_9v.si, _9y.tim, _9u.si, _9v.fdt, _9y.tip, _9w.prx, _9u.fdx, _68.fdt, _68.fdx, _9u.fdt, _9v.tim,
_9u.tip, _9w.tim, _9w.tip, _9t.fdt, _68.frq, _9u.tim, _9w_nrm.cfe, _9v.tip, _68.prx, _9y.prx,
_9t.fdx, _9t.prx, _9w_nrm.cfs, _9v.fnm, _9x.frq, _9v.prx, _9y.si, _9x.fdt, _9x.prx, _9t.tim,
_9y.frq, _9t.tip, _9y.fdx, _9x.fnm, _9t_nrm.cfe, _68.tim, _9y.fdt, _9x.fdx, _68.tip, _9u.prx,
_9y.fnm, segments_4, _68_nrm.cfe]
[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-1343178849890/jetty1/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a0b78dd; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_5,generation=5,filenames=[_a0.tip,
_aq.frq, _a0.tim, _as.frq, _ap.fnm, _as.fnm, _aq.fnm, _ar_nrm.cfs, _as_nrm.cfe, _ap.si, _a0_nrm.cfs,
_ar.fdt, _ar_nrm.cfe, _a0.frq, _ar.fdx, _as.tip, _as_nrm.cfs, _as.tim, _ap.tip, _a0.fnm, _a0_nrm.cfe,
_ap.tim, _ar.prx, _aq.si, _ap.fdt, _ap.fdx, _aq.tip, _ap.prx, _a0.si, _a0_1.del, _ap.frq,
_aq.tim, _ar.frq, _as.si, _ar.fnm, _ar.si, _ap_1.del, _ar.tim, _a0.prx, _ap_nrm.cfs, _ar.tip,
_aq_nrm.cfs, _as.prx, _aq.prx, _ap_nrm.cfe, _aq.fdt, _as.fdt, _a0.fdx, _as.fdx, _a0.fdt, segments_5,
_aq_nrm.cfe, _aq.fdx]
[junit4:junit4]   2> 37449 T322 C4 P57143 oasc.SolrDeletionPolicy.updateCommits newest
commit = 5
[junit4:junit4]   2> 37464 T322 C4 P57143 oass.SolrIndexSearcher.<init> Opening Searcher@2bf0f9d6
main
[junit4:junit4]   2> 37465 T322 C4 P57143 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 37466 T330 oasc.SolrCore.registerSearcher [collection1] Registered
new searcher Searcher@2bf0f9d6 main{StandardDirectoryReader(segments_5:829 _a0(5.0):C1635/25
_ap(5.0):C132/13 _aq(5.0):C10 _ar(5.0):C10 _as(5.0):C6)}
[junit4:junit4]   2> 37469 T2025 C5 P57145 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 37603 T2025 C5 P57145 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-1343178849890/jetty2/index.20120724211445903
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5fc9ba45; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_5,generation=5,filenames=[_ai.fnm,
_a1.tip, _a1.fnm, _ai_1.del, _ai.fdt, _aj.frq, _a1.tim, _ai_nrm.cfe, _aj.fnm, _aj_nrm.cfe,
_a1_1.del, _aj.fdx, _aj.fdt, _a1.si, _ai.fdx, _aj.prx, _ai.frq, _aj_nrm.cfs, _ai_nrm.cfs,
_aj.si, _a1.frq, _ai.tim, _a1.prx, _ai.tip, _a1.fdt, _a1_nrm.cfs, _a1.fdx, _aj.tim, _aj.tip,
_a1_nrm.cfe, _ai.si, segments_5, _ai.prx]
[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-1343178849890/jetty2/index.20120724211445903
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5fc9ba45; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_6,generation=6,filenames=[_aq.frq,
_as.frq, _a1.fnm, _aq.fnm, _as.fnm, _at.tip, _at.tim, _at.frq, _a1.frq, _ar_nrm.cfs, _at.prx,
_at.fnm, _as_nrm.cfe, _ar.fdt, _ar_nrm.cfe, _ar.fdx, _as.tip, _as_nrm.cfs, _as.tim, _at_nrm.cfs,
_ar.prx, _a1.tip, _aq.si, _a1.tim, _aq.tip, _at_nrm.cfe, _ar.frq, _aq.tim, _a1_1.del, _as.si,
_ar.fnm, _a1.si, _ar.si, _at.fdx, _ar.tim, _ar_1.del, _a1.prx, _at.si, _ar.tip, _at.fdt, _a1.fdt,
_a1_nrm.cfs, _aq_nrm.cfs, _a1.fdx, _as.prx, _a1_nrm.cfe, _aq.prx, _aq.fdt, _as.fdt, _as.fdx,
segments_6, _aq_nrm.cfe, _aq.fdx]
[junit4:junit4]   2> 37604 T2025 C5 P57145 oasc.SolrDeletionPolicy.updateCommits newest
commit = 6
[junit4:junit4]   2> 37608 T2025 C5 P57145 oass.SolrIndexSearcher.<init> Opening
Searcher@6b9dc68a main
[junit4:junit4]   2> 37608 T2025 C5 P57145 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 37609 T2120 oasc.SolrCore.registerSearcher [collection1] Registered
new searcher Searcher@6b9dc68a main{StandardDirectoryReader(segments_6:831:nrt _a1(5.0):C1634/24
_ar(5.0):C131/12 _aq(5.0):C10 _as(5.0):C10 _at(5.0):C5)}
[junit4:junit4]   2> 37609 T2025 C5 P57145 /update {waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2}
{commit=} 0 140
[junit4:junit4]   2> 37610 T322 C4 P57143 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2}
{commit=} 0 296
[junit4:junit4]   2> 37613 T321 C4 P57143 REQ /select {distrib=false&wt=javabin&q=*:*&version=2}
hits=1755 status=0 QTime=0 
[junit4:junit4]   2> 37616 T2041 C5 P57145 REQ /select {distrib=false&wt=javabin&q=*:*&version=2}
hits=1754 status=0 QTime=1 
[junit4:junit4]   2> 37619 T321 C4 P57143 REQ /select {distrib=false&wt=javabin&q=*:*&version=2}
hits=1755 status=0 QTime=0 
[junit4:junit4]   2> 37622 T2041 C5 P57145 REQ /select {distrib=false&wt=javabin&q=*:*&version=2}
hits=1754 status=0 QTime=0 
[junit4:junit4]   2> 37625 T299 oazs.PrepRequestProcessor.pRequest Processed session termination
for sessionid: 0x138bbb2bea00006
[junit4:junit4]   2> 37626 T349 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 37626 T296 oazs.NIOServerCnxn.closeSock Closed socket connection for
client /140.211.11.196:57151 which had sessionid 0x138bbb2bea00006
[junit4:junit4]   2> 37626 T294 oaz.ZooKeeper.close Session: 0x138bbb2bea00006 closed
[junit4:junit4]   2> 37626 T299 oazs.PrepRequestProcessor.pRequest Processed session termination
for sessionid: 0x138bbb2bea00005
[junit4:junit4]   2> 37627 T345 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 37627 T296 oazs.NIOServerCnxn.closeSock Closed socket connection for
client /140.211.11.196:57147 which had sessionid 0x138bbb2bea00005
[junit4:junit4]   2> 37627 T294 oaz.ZooKeeper.close Session: 0x138bbb2bea00005 closed
[junit4:junit4]   2> 37627 T299 oazs.PrepRequestProcessor.run PrepRequestProcessor exited
loop!
[junit4:junit4]   2> 37627 T298 oazs.SyncRequestProcessor.run SyncRequestProcessor exited!
[junit4:junit4]   2> 37627 T294 oazs.FinalRequestProcessor.shutdown shutdown of request
processor complete
[junit4:junit4]   2> 37761 T294 oazs.NIOServerCnxn.closeSock Closed socket connection for
client /140.211.11.196:57144 which had sessionid 0x138bbb2bea00003
[junit4:junit4]   2> 37761 T294 oazs.NIOServerCnxn.closeSock Closed socket connection for
client /140.211.11.196:57138 which had sessionid 0x138bbb2bea00002
[junit4:junit4]   2> 37761 T328 oaz.ClientCnxn$SendThread.run Unable to read additional
data from server sessionid 0x138bbb2bea00003, likely server has closed socket, closing socket
connection and attempting reconnect
[junit4:junit4]   2> 37761 T314 oaz.ClientCnxn$SendThread.run Unable to read additional
data from server sessionid 0x138bbb2bea00002, likely server has closed socket, closing socket
connection and attempting reconnect
[junit4:junit4]   2> 37761 T294 oazs.NIOServerCnxn.closeSock Closed socket connection for
client /140.211.11.196:57193 which had sessionid 0x138bbb2bea00007
[junit4:junit4]   2> 37762 T296 oazs.NIOServerCnxn$Factory.run NIOServerCnxn factory exited
run method
[junit4:junit4]   2> 37762 T2031 oaz.ClientCnxn$SendThread.run Unable to read additional
data from server sessionid 0x138bbb2bea00007, likely server has closed socket, closing socket
connection and attempting reconnect
[junit4:junit4]   2> 37763 T294 oazs.FinalRequestProcessor.shutdown shutdown of request
processor complete
[junit4:junit4]   2> 37764 T294 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 57137
[junit4:junit4]   2> 37764 T294 oasc.CoreContainer.shutdown Shutting down CoreContainer
instance=1612715419
[junit4:junit4]   2> 37764 T294 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@3d20f4b6
[junit4:junit4]   2> 37766 T294 oasc.SolrCore.closeSearcher [collection1] Closing main
searcher on request.
[junit4:junit4]   2> 37769 T294 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=3383,cumulative_deletesById=1628,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 37862 T315 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4bcfdfd9
name:ZooKeeperConnection Watcher:127.0.0.1:57134/solr got event WatchedEvent state:Disconnected
type:None path:null path:null type:None
[junit4:junit4]   2> 37862 T294 oaz.ZooKeeper.close Session: 0x138bbb2bea00002 closed
[junit4:junit4]   2> 37862 T317 oasc.OverseerCollectionProcessor.run WARNING Overseer cannot
talk to ZK
[junit4:junit4]   2> 37862 T315 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 37863 T294 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 37863 T329 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3d154dd1
name:ZooKeeperConnection Watcher:127.0.0.1:57134/solr got event WatchedEvent state:Disconnected
type:None path:null path:null type:None
[junit4:junit4]   2> 37863 T2032 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@20a26542
name:ZooKeeperConnection Watcher:127.0.0.1:57134/solr got event WatchedEvent state:Disconnected
type:None path:null path:null type:None
[junit4:junit4]   2> 37915 T294 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 57143
[junit4:junit4]   2> 37915 T294 oasc.CoreContainer.shutdown Shutting down CoreContainer
instance=1296447545
[junit4:junit4]   2> 37915 T294 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@7454bcf6
[junit4:junit4]   2> 37917 T294 oasc.SolrCore.closeSearcher [collection1] Closing main
searcher on request.
[junit4:junit4]   2> 37917 T294 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=3383,cumulative_deletesById=1628,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 38098 T316 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> 38098 T316 oasc.Overseer$CloudStateUpdater.amILeader According to
ZK I (id=88026569110454274-127.0.0.1:57137_solr-n_0000000000) am no longer a leader.
[junit4:junit4]   2> 38115 T297 oazs.SessionTrackerImpl.run SessionTrackerImpl exited loop!
[junit4:junit4]   2> 38947 T2031 oaz.ClientCnxn$SendThread.startConnect Opening socket
connection to server localhost/127.0.0.1:57134
[junit4:junit4]   2> 39048 T328 oaz.ClientCnxn$SendThread.startConnect Opening socket connection
to server localhost/127.0.0.1:57134
[junit4:junit4]   2> 49864 T2031 oaz.ClientCnxn$SendThread.run Client session timed out,
have not heard from server in 12001ms for sessionid 0x138bbb2bea00007, closing socket connection
and attempting reconnect
[junit4:junit4]   2> 49964 T329 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 49964 T294 oaz.ZooKeeper.close Session: 0x138bbb2bea00003 closed
[junit4:junit4]   2> 49965 T294 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 50017 T294 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 57145
[junit4:junit4]   2> 50017 T294 oasc.CoreContainer.shutdown Shutting down CoreContainer
instance=1157524357
[junit4:junit4]   2> 50017 T294 oasc.RecoveryStrategy.close WARNING Stopping recovery for
core collection1 zkNodeName=127.0.0.1:57145_solr_collection1
[junit4:junit4]   2> 50018 T294 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@6dce758e
[junit4:junit4]   2> 50021 T294 oasc.SolrCore.closeSearcher [collection1] Closing main
searcher on request.
[junit4:junit4]   2> 50021 T294 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=1792,cumulative_deletesById=876,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 50023 T294 oasc.RecoveryStrategy.close WARNING Stopping recovery for
core collection1 zkNodeName=127.0.0.1:57145_solr_collection1
[junit4:junit4]   2> 51516 T2031 oaz.ClientCnxn$SendThread.startConnect Opening socket
connection to server localhost/127.0.0.1:57134
[junit4:junit4]   2> 62067 T2032 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 62067 T294 oaz.ZooKeeper.close Session: 0x138bbb2bea00007 closed
[junit4:junit4]   2> 62068 T294 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4]   2> 62152 T294 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=RecoveryZkTest -Dtests.method=testDistribSearch
-Dtests.seed=37E23162C3EDAAD2 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es_CL
-Dtests.timezone=America/New_York -Dtests.file.encoding=UTF-8
[junit4:junit4]   2>
[junit4:junit4]    > (@AfterClass output)
[junit4:junit4]   2> 62179 T294 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=Appending, sim=DefaultSimilarity, locale=es_CL,
timezone=America/New_York
[junit4:junit4]   2> NOTE: FreeBSD 9.0-RELEASE amd64/Sun Microsystems Inc. 1.6.0_32 (64-bit)/cpus=16,threads=9,free=154882776,total=291438592
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestSwedishLightStemFilterFactory,
PreAnalyzedFieldTest, IndexBasedSpellCheckerTest, TestPerFieldSimilarity, SolrInfoMBeanTest,
JSONWriterTest, TestRandomFaceting, PingRequestHandlerTest, TestThaiWordFilterFactory, TestBadConfig,
RecoveryZkTest]
[junit4:junit4]   2> 
[junit4:junit4] Completed on J0 in 62.19s, 1 test, 1 failure <<< FAILURES!
[...truncated 744 lines...]

[...truncated 29361 lines...]

[...truncated 29361 lines...]

[...truncated 29361 lines...]

[...truncated 29361 lines...]

[...truncated 29346 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: 297 suites, 1055 tests, 1 failure, 5 ignored

Total time: 26 minutes 28 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



Mime
View raw message