Build: https://builds.apache.org/job/Lucene-Solr-tests-only-4.x/357/
1 tests failed.
FAILED: org.apache.solr.cloud.RecoveryZkTest.testDistribSearch
Error Message:
expected:<1690> but was:<1691>
Stack Trace:
java.lang.AssertionError: expected:<1690> but was:<1691>
at
__randomizedtesting.SeedInfo.seed([590647FDF02F84C:8476EA67A85D9870]: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
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 27953 lines...]
[junit4:junit4] 2> 35406 T1828 C117 P61699 /update {wt=javabin&version=2}
{add=[1601 (1408790510944911360)]} 0 3
[junit4:junit4] 2> 35408 T3409 C118 P49196 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1
[junit4:junit4] 2> 35408 T1827 C117 P61699 /update {wt=javabin&version=2}
{add=[11599 (1408790510947008512)]} 0 3
[junit4:junit4] 2> 35409 T1813 C116 P61740 /update {wt=javabin&version=2}
{delete=[1522 (-1408790510951202816)]} 0 0
[junit4:junit4] 2> 35411 T1812 C116 P61740 /update {wt=javabin&version=2}
{add=[11600 (1408790510953299968)]} 0 0
[junit4:junit4] 2> 35413 T3409 C118 P49196 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0
[junit4:junit4] 2> 35413 T1827 C117 P61699 /update {wt=javabin&version=2}
{delete=[1522 (-1408790510953299968)]} 0 2
[junit4:junit4] 2> 35415 T1812 C116 P61740 /update {wt=javabin&version=2}
{add=[1602 (1408790510957494272)]} 0 0
[junit4:junit4] 2> 35417 T3409 C118 P49196 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1
[junit4:junit4] 2> 35417 T1828 C117 P61699 /update {wt=javabin&version=2}
{add=[11600 (1408790510956445696)]} 0 3
[junit4:junit4] 2> 35420 T3409 C118 P49196 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1
[junit4:junit4] 2> 35420 T1812 C116 P61740 /update {wt=javabin&version=2}
{add=[11601 (1408790510962737152)]} 0 0
[junit4:junit4] 2> 35420 T1827 C117 P61699 /update {wt=javabin&version=2}
{add=[1602 (1408790510959591424)]} 0 3
[junit4:junit4] 2> 35423 T1812 C116 P61740 /update {wt=javabin&version=2}
{delete=[1525 (-1408790510965882880)]} 0 0
[junit4:junit4] 2> 35425 T3409 C118 P49196 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1
[junit4:junit4] 2> 35425 T1828 C117 P61699 /update {wt=javabin&version=2}
{add=[11601 (1408790510964834304)]} 0 3
[junit4:junit4] 2> 35427 T3410 C118 P49196 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0
[junit4:junit4] 2> 35427 T1827 C117 P61699 /update {wt=javabin&version=2}
{delete=[1525 (-1408790510967980032)]} 0 2
[junit4:junit4] 2> 35428 T1812 C116 P61740 /update {wt=javabin&version=2}
{delete=[11522 (-1408790510971125760)]} 0 0
[junit4:junit4] 2> 35429 T1813 C116 P61740 /update {wt=javabin&version=2}
{add=[1603 (1408790510972174336)]} 0 0
[junit4:junit4] 2> 35432 T3410 C118 P49196 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0
[junit4:junit4] 2> 35432 T1827 C117 P61699 /update {wt=javabin&version=2}
{delete=[11522 (-1408790510973222912)]} 0 2
[junit4:junit4] 2> 35435 T3410 C118 P49196 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1
[junit4:junit4] 2> 35435 T1813 C116 P61740 /update {wt=javabin&version=2}
{add=[11602 (1408790510978465792)]} 0 0
[junit4:junit4] 2> 35435 T1828 C117 P61699 /update {wt=javabin&version=2}
{add=[1603 (1408790510974271488)]} 0 4
[junit4:junit4] 2> 35438 T1813 C116 P61740 /update {wt=javabin&version=2}
{delete=[1526 (-1408790510981611520)]} 0 0
[junit4:junit4] 2> 35440 T3410 C118 P49196 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1
[junit4:junit4] 2> 35440 T1827 C117 P61699 /update {wt=javabin&version=2}
{add=[11602 (1408790510980562944)]} 0 3
[junit4:junit4] 2> 35442 T3409 C118 P49196 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0
[junit4:junit4] 2> 35442 T1828 C117 P61699 /update {wt=javabin&version=2}
{delete=[1526 (-1408790510983708672)]} 0 2
[junit4:junit4] 2> 35443 T1813 C116 P61740 /update {wt=javabin&version=2}
{delete=[11525 (-1408790510986854400)]} 0 0
[junit4:junit4] 2> 35445 T1812 C116 P61740 /update {wt=javabin&version=2}
{add=[1604 (1408790510988951552)]} 0 0
[junit4:junit4] 2> 35447 T3409 C118 P49196 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=0
[junit4:junit4] 2> 35447 T1828 C117 P61699 /update {wt=javabin&version=2}
{delete=[11525 (-1408790510988951552)]} 0 2
[junit4:junit4] 2> 35449 T1800 oascc.ZkStateReader.updateCloudState Updating
cloud state from ZooKeeper...
[junit4:junit4] 2> 35450 T3409 C118 P49196 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1
[junit4:junit4] 2> 35450 T1812 C116 P61740 /update {wt=javabin&version=2}
{add=[11603 (1408790510994194432)]} 0 0
[junit4:junit4] 2> 35450 T1827 C117 P61699 /update {wt=javabin&version=2}
{add=[1604 (1408790510991048704)]} 0 3
[junit4:junit4] 2> added docs:1604 with 0 fails deletes:759
[junit4:junit4] 2> 35456 T3409 C118 P49196 REQ /update
{update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2
[junit4:junit4] 2> 35456 T1827 C117 P61699 /update {wt=javabin&version=2}
{add=[11603 (1408790510996291584)]} 0 4
[junit4:junit4] 2> added docs:1603 with 1 fails deletes:758
[junit4:junit4] 2> 35458 T1812 C116 P61740 oasu.DirectUpdateHandler2.commit
start
commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4] 2> 35664 T1812 C116 P61740 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-4.x/checkout/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1343527292084/control/data/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18f7a1e;
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-4.x/checkout/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1343527292084/control/data/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@18f7a1e;
maxCacheMB=48.0
maxMergeSizeMB=4.0),segFN=segments_2,generation=2,filenames=[_a5.fdx, _a5.si,
_a1.fnm, _a5.fdt, _61.nrm, _a2.nrm, _a3.tis, _61.tis, _a2.fdt, _a5.prx,
_61.tii, _a2.fdx, _a4.frq, _a5.tis, _a5.nrm, _a4.prx, _a4.fnm, _a1.frq,
_61.fnm, _61.prx, _a3.nrm, _61.frq, _a5.fnm, _61.fdt, _a3.fdx, _a5.frq,
_61.fdx, _a3.fdt, _a4.nrm, _a4.tii, _a2.tis, _61_1.del, _a1.tis, _a4.tis,
_a1.tii, _a2.tii, _a3.frq, _a1.si, _a4.fdx, _a3.prx, _a4.fdt, _61.si, _a1.prx,
_a2.fnm, _a1.fdt, _a2.prx, _a1.fdx, _a5.tii, _a2.si, _a2.frq, _a3.fnm, _a3.tii,
_a1.nrm, _a3.si, _a4.si, segments_2, _a2_1.del]
[junit4:junit4] 2> 35665 T1812 C116 P61740
oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4] 2> 35675 T1812 C116 P61740 oass.SolrIndexSearcher.<init>
Opening Searcher@6059cd01 main
[junit4:junit4] 2> 35676 T1812 C116 P61740 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4:junit4] 2> 35676 T1823 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@6059cd01
main{StandardDirectoryReader(segments_2:779 _61(4.0.0.1):C1009/66
_a2(4.0.0.1):C726/16 _a1(4.0.0.1):C10 _a3(4.0.0.1):C10 _a4(4.0.0.1):C10
_a5(4.0.0.1):C7)}
[junit4:junit4] 2> 35678 T1812 C116 P61740 /update
{waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=}
0 220
[junit4:junit4] 2> 35680 T1827 C117 P61699 oasu.DirectUpdateHandler2.commit
start
commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4] 2> 35940 T1827 C117 P61699 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-4.x/checkout/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1343527292084/jetty1/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@27445506;
maxCacheMB=48.0
maxMergeSizeMB=4.0),segFN=segments_3,generation=3,filenames=[_5l.fdx, _5l.fdt,
_5k.tis, _5l.tis, _5l.nrm, _5k.frq, _5k.fdx, _5l.frq, _5k.si, _5l.tii, _5k.fnm,
_5k.nrm, _5k.prx, _5l.si, _5l.prx, _5l.fnm, _5k.fdt, segments_3, _5k.tii,
_5k_1.del]
[junit4:junit4] 2>
commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-4.x/checkout/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1343527292084/jetty1/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@27445506;
maxCacheMB=48.0
maxMergeSizeMB=4.0),segFN=segments_4,generation=4,filenames=[_8z.fdx, _91.fdx,
_8z.fnm, _61.nrm, _91.frq, _61.tis, _8z.fdt, _8y.frq, _90.tii, _61.tii,
_8z.frq, _8x.prx, _8x_1.del, _8z.prx, _8x.si, _90.si, _93.tii, _61.fnm,
_90.prx, _8z.tis, _8y.si, _90.fdt, _61.prx, _93.fdt, _93.tis, _61.frq, _8z.si,
_93.fdx, _8z.tii, _90.tis, _91.prx, _61.fdt, _8x.fnm, _90.nrm, _61.fdx,
_8y.fnm, _8z.nrm, _8y.prx, _91.fnm, _8x.frq, _92.prx, _61_1.del, _8y.tis,
_90.fnm, _92.si, _90.frq, _8y.fdx, _93.si, _8x.tii, _8y.tii, _93.nrm, _8y.fdt,
_91.tii, _8x.nrm, _8x.tis, _93.prx, _92.nrm, _92.fdx, _92.tis, _90.fdx, _91.si,
_92.fdt, _92.fnm, _61.si, _8x.fdx, _93.fnm, _92.tii, _92.frq, _91.tis, _8x.fdt,
_91.nrm, _93.frq, _91.fdt, segments_4, _8y.nrm]
[junit4:junit4] 2>
commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-4.x/checkout/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1343527292084/jetty1/index
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@27445506;
maxCacheMB=48.0
maxMergeSizeMB=4.0),segFN=segments_5,generation=5,filenames=[_a5.fdx, _95.nrm,
_a5.si, _a1.fnm, _a5.fdt, _a2.nrm, _a3.tis, _a6.fnm, _a2.fdt, _a5.prx, _95.si,
_a2.fdx, _a6.frq, _a4.frq, _a5.tis, _a5.nrm, _a4.prx, _a6.fdx, _a7.nrm,
_a4.fnm, _a7.fdt, _a1.frq, _a7.fdx, _a6.tii, _95_1.del, _95.frq, _a3.nrm,
_a6.nrm, _a7.frq, _a6.fdt, _a5.fnm, _a6.si, _a3.fdx, _a5.frq, _a3.fdt, _a7.tis,
_a4.nrm, _a7.prx, _a4.tii, _a2.tis, _a7.si, _a7.tii, _a6.prx, _95.prx, _a1.tis,
_a4.tis, _a1.tii, _a2.tii, _a3.frq, _a1_1.del, _a1.si, _a4.fdx, _a3.prx,
_a7.fnm, _a6.tis, _a4.fdt, _95.fnm, _a1.prx, _a2.fnm, _a1.fdt, _95.tii,
_a2.prx, _a1.fdx, _a5.tii, _95.tis, _a2.frq, _a2.si, _a3.fnm, _a3.tii, _a1.nrm,
_a3.si, _a4.si, _95.fdx, segments_5, _95.fdt]
[junit4:junit4] 2> 35941 T1827 C117 P61699
oasc.SolrDeletionPolicy.updateCommits newest commit = 5
[junit4:junit4] 2> 35958 T1827 C117 P61699 oass.SolrIndexSearcher.<init>
Opening Searcher@5e086d64 main
[junit4:junit4] 2> 35959 T1827 C117 P61699 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4:junit4] 2> 35959 T1835 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@5e086d64
main{StandardDirectoryReader(segments_5:784 _95(4.0.0.1):C1512/66
_a1(4.0.0.1):C219/28 _a2(4.0.0.1):C10 _a3(4.0.0.1):C10 _a4(4.0.0.1):C10
_a5(4.0.0.1):C10 _a6(4.0.0.1):C10 _a7(4.0.0.1):C3)}
[junit4:junit4] 2> 35965 T3409 C118 P49196 oasu.DirectUpdateHandler2.commit
start
commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4] 2> 36193 T3409 C118 P49196 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-4.x/checkout/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1343527292084/jetty2/index.20120729070205874
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5134a4b4;
maxCacheMB=48.0
maxMergeSizeMB=4.0),segFN=segments_5,generation=5,filenames=[_9o.tii, _9o.nrm,
_96.si, _9n.nrm, _9p.fnm, _9o.fdt, _9o.si, _9o.fdx, _9m.prx, _9m.tis, _96.prx,
_96_1.del, _9p.fdx, _9p.frq, _96.frq, _9n.si, _9n.fnm, _96.fnm, _9n.fdt,
_9n.tii, _9p.tii, _9p.fdt, _9n.tis, _9m.nrm, _96.tii, _9p.tis, _9o.prx,
_9m.tii, _96.tis, _9n.fdx, _9p.prx, _9o.fnm, _96.fdx, _9m.fdx, _9n.frq, _9p.si,
_9p.nrm, _9m_1.del, _9n.prx, _96.nrm, _9m.fnm, _9m.fdt, _9o.frq, _9m.si,
_9o.tis, _96.fdt, segments_5, _9m.frq]
[junit4:junit4] 2>
commit{dir=NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-4.x/checkout/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-1343527292084/jetty2/index.20120729070205874
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5134a4b4;
maxCacheMB=48.0
maxMergeSizeMB=4.0),segFN=segments_6,generation=6,filenames=[_a5.fdx, _a5.si,
_a2.nrm, _a3.tis, _a5.fdt, _a6.fnm, _a2.fdt, _a5.prx, _a2.fdx, _a6.frq,
_a4.frq, _a8.si, _96_1.del, _96.prx, _a3_1.del, _a5.tis, _a5.nrm, _96.frq,
_a4.prx, _a8.tis, _a6.fdx, _a7.nrm, _a4.fnm, _96.fnm, _a7.fdt, _a7.fdx,
_a6.tii, _96.tii, _a3.nrm, _a6.nrm, _a7.frq, _a6.fdt, _96.tis, _a5.fnm,
_96.fdx, _a8.fnm, _a6.si, _a3.fdx, _a5.frq, _a3.fdt, _a8.frq, _a7.tis, _a4.nrm,
_a7.prx, _a4.tii, _96.fdt, _a2.tis, _a7.si, _a7.tii, _a6.prx, _96.si, _a4.tis,
_a8.nrm, _a8.fdx, _a2.tii, _a3.frq, _a4.fdx, _a3.prx, _a7.fnm, _a6.tis,
_a4.fdt, _a2.fnm, _a8.tii, _a2.prx, _a5.tii, _a2.frq, _a2.si, _a8.prx, _a3.fnm,
_a3.tii, _a8.fdt, _a3.si, _96.nrm, _a4.si, segments_6]
[junit4:junit4] 2> 36194 T3409 C118 P49196
oasc.SolrDeletionPolicy.updateCommits newest commit = 6
[junit4:junit4] 2> 36201 T3409 C118 P49196 oass.SolrIndexSearcher.<init>
Opening Searcher@3863999 main
[junit4:junit4] 2> 36202 T3409 C118 P49196 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4:junit4] 2> 36202 T3523 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@3863999
main{StandardDirectoryReader(segments_6:787:nrt _96(4.0.0.1):C1506/60
_a3(4.0.0.1):C215/30 _a2(4.0.0.1):C10 _a4(4.0.0.1):C10 _a5(4.0.0.1):C10
_a6(4.0.0.1):C10 _a7(4.0.0.1):C10 _a8(4.0.0.1):C10)}
[junit4:junit4] 2> 36203 T3409 C118 P49196 /update
{waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&softCommit=false&version=2}
{commit=} 0 238
[junit4:junit4] 2> 36203 T1827 C117 P61699 /update
{waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=}
0 523
[junit4:junit4] 2> 36206 T1826 C117 P61699 REQ /select
{distrib=false&wt=javabin&q=*:*&version=2} hits=1690 status=0 QTime=0
[junit4:junit4] 2> 36208 T3441 C118 P49196 REQ /select
{distrib=false&wt=javabin&q=*:*&version=2} hits=1691 status=0 QTime=0
[junit4:junit4] 2> 36211 T1826 C117 P61699 REQ /select
{distrib=false&wt=javabin&q=*:*&version=2} hits=1690 status=0 QTime=0
[junit4:junit4] 2> 36214 T3441 C118 P49196 REQ /select
{distrib=false&wt=javabin&q=*:*&version=2} hits=1691 status=0 QTime=0
[junit4:junit4] 2> 36215 T1805 oazs.PrepRequestProcessor.pRequest Processed
session termination for sessionid: 0x138d0778cb90006
[junit4:junit4] 2> 36216 T1854 oaz.ClientCnxn$EventThread.run EventThread
shut down
[junit4:junit4] 2> 36216 T1802 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /140.211.11.196:58960 which had sessionid
0x138d0778cb90006
[junit4:junit4] 2> 36216 T1800 oaz.ZooKeeper.close Session: 0x138d0778cb90006
closed
[junit4:junit4] 2> 36217 T1805 oazs.PrepRequestProcessor.pRequest Processed
session termination for sessionid: 0x138d0778cb90005
[junit4:junit4] 2> 36217 T1850 oaz.ClientCnxn$EventThread.run EventThread
shut down
[junit4:junit4] 2> 36218 T1802 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /140.211.11.196:51684 which had sessionid
0x138d0778cb90005
[junit4:junit4] 2> 36217 T1800 oaz.ZooKeeper.close Session: 0x138d0778cb90005
closed
[junit4:junit4] 2> 36218 T1804 oazs.SyncRequestProcessor.run
SyncRequestProcessor exited!
[junit4:junit4] 2> 36218 T1805 oazs.PrepRequestProcessor.run
PrepRequestProcessor exited loop!
[junit4:junit4] 2> 36219 T1800 oazs.FinalRequestProcessor.shutdown shutdown
of request processor complete
[junit4:junit4] 2> 36349 T1800 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /140.211.11.196:46134 which had sessionid
0x138d0778cb90002
[junit4:junit4] 2> 36349 T1819 oaz.ClientCnxn$SendThread.run Unable to read
additional data from server sessionid 0x138d0778cb90002, likely server has
closed socket, closing socket connection and attempting reconnect
[junit4:junit4] 2> 36349 T1800 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /140.211.11.196:20377 which had sessionid
0x138d0778cb90003
[junit4:junit4] 2> 36350 T1833 oaz.ClientCnxn$SendThread.run Unable to read
additional data from server sessionid 0x138d0778cb90003, likely server has
closed socket, closing socket connection and attempting reconnect
[junit4:junit4] 2> 36350 T1800 oazs.NIOServerCnxn.closeSock Closed socket
connection for client /140.211.11.196:16883 which had sessionid
0x138d0778cb90007
[junit4:junit4] 2> 36350 T3416 oaz.ClientCnxn$SendThread.run Unable to read
additional data from server sessionid 0x138d0778cb90007, likely server has
closed socket, closing socket connection and attempting reconnect
[junit4:junit4] 2> 36350 T1802 oazs.NIOServerCnxn$Factory.run NIOServerCnxn
factory exited run method
[junit4:junit4] 2> 36351 T1800 oazs.FinalRequestProcessor.shutdown shutdown
of request processor complete
[junit4:junit4] 2> 36351 T1800 oasc.ChaosMonkey.monkeyLog monkey: stop shard!
61740
[junit4:junit4] 2> 36351 T1800 oasc.CoreContainer.shutdown Shutting down
CoreContainer instance=54812570
[junit4:junit4] 2> 36352 T1800 oasc.SolrCore.close [collection1] CLOSING
SolrCore org.apache.solr.core.SolrCore@3b83a4cd
[junit4:junit4] 2> 36353 T1800 oasc.SolrCore.closeSearcher [collection1]
Closing main searcher on request.
[junit4:junit4] 2> 36354 T1800 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=3207,cumulative_deletesById=1517,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4] 2> 36451 T3417 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@579a3c95
name:ZooKeeperConnection Watcher:127.0.0.1:45547/solr got event WatchedEvent
state:Disconnected type:None path:null path:null type:None
[junit4:junit4] 2> 36451 T1822 oasc.OverseerCollectionProcessor.run WARNING
Overseer cannot talk to ZK
[junit4:junit4] 2> 36451 T1800 oaz.ZooKeeper.close Session: 0x138d0778cb90002
closed
[junit4:junit4] 2> 36451 T1821 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> 36453 T1800 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> 36453 T1821 oasc.Overseer$CloudStateUpdater.amILeader
According to ZK I (id=88049404614344706-127.0.0.1:61740_solr-n_0000000000) am
no longer a leader.
[junit4:junit4] 2> 36451 T1834 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1a12b357
name:ZooKeeperConnection Watcher:127.0.0.1:45547/solr got event WatchedEvent
state:Disconnected type:None path:null path:null type:None
[junit4:junit4] 2> 36451 T1820 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1c30e7be
name:ZooKeeperConnection Watcher:127.0.0.1:45547/solr got event WatchedEvent
state:Disconnected type:None path:null path:null type:None
[junit4:junit4] 2> 36453 T1820 oaz.ClientCnxn$EventThread.run EventThread
shut down
[junit4:junit4] 2> 36505 T1800 oasc.ChaosMonkey.monkeyLog monkey: stop shard!
61699
[junit4:junit4] 2> 36505 T1800 oasc.CoreContainer.shutdown Shutting down
CoreContainer instance=1178774080
[junit4:junit4] 2> 36505 T1800 oasc.SolrCore.close [collection1] CLOSING
SolrCore org.apache.solr.core.SolrCore@3b8e6307
[junit4:junit4] 2> 36506 T1800 oasc.SolrCore.closeSearcher [collection1]
Closing main searcher on request.
[junit4:junit4] 2> 36507 T1800 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=3207,cumulative_deletesById=1517,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4] 2> 36919 T1803 oazs.SessionTrackerImpl.run SessionTrackerImpl
exited loop!
[junit4:junit4] 2> 37754 T3416 oaz.ClientCnxn$SendThread.startConnect Opening
socket connection to server localhost/127.0.0.1:45547
[junit4:junit4] 2> 38375 T1833 oaz.ClientCnxn$SendThread.startConnect Opening
socket connection to server localhost/127.0.0.1:45547
[junit4:junit4] 2> 44452 T3416 oaz.ClientCnxn$SendThread.run Client session
timed out, have not heard from server in 8001ms for sessionid
0x138d0778cb90007, closing socket connection and attempting reconnect
[junit4:junit4] 2> 44553 T1834 oaz.ClientCnxn$EventThread.run EventThread
shut down
[junit4:junit4] 2> 44553 T1800 oaz.ZooKeeper.close Session: 0x138d0778cb90003
closed
[junit4:junit4] 2> 44554 T1800 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> 44607 T1800 oasc.ChaosMonkey.monkeyLog monkey: stop shard!
49196
[junit4:junit4] 2> 44607 T1800 oasc.CoreContainer.shutdown Shutting down
CoreContainer instance=890443077
[junit4:junit4] 2> 44607 T1800 oasc.RecoveryStrategy.close WARNING Stopping
recovery for core collection1 zkNodeName=127.0.0.1:49196_solr_collection1
[junit4:junit4] 2> 44608 T1800 oasc.SolrCore.close [collection1] CLOSING
SolrCore org.apache.solr.core.SolrCore@633cd749
[junit4:junit4] 2> 44610 T1800 oasc.SolrCore.closeSearcher [collection1]
Closing main searcher on request.
[junit4:junit4] 2> 44610 T1800 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=1716,cumulative_deletesById=830,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4] 2> 44611 T1800 oasc.RecoveryStrategy.close WARNING Stopping
recovery for core collection1 zkNodeName=127.0.0.1:49196_solr_collection1
[junit4:junit4] 2> 46187 T3416 oaz.ClientCnxn$SendThread.startConnect Opening
socket connection to server localhost/127.0.0.1:45547
[junit4:junit4] 2> 52655 T3417 oaz.ClientCnxn$EventThread.run EventThread
shut down
[junit4:junit4] 2> 52655 T1800 oaz.ZooKeeper.close Session: 0x138d0778cb90007
closed
[junit4:junit4] 2> 52656 T1800 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/solr,null}
[junit4:junit4] 2> 52753 T1800 oas.SolrTestCaseJ4.tearDown ###Ending
testDistribSearch
[junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=RecoveryZkTest
-Dtests.method=testDistribSearch -Dtests.seed=590647FDF02F84C
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=pl_PL
-Dtests.timezone=Indian/Maldives -Dtests.file.encoding=UTF-8
[junit4:junit4] 2>
[junit4:junit4] > (@AfterClass output)
[junit4:junit4] 2> 52811 T1800 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4] 2> NOTE: test params are: codec=Lucene3x,
sim=RandomSimilarityProvider(queryNorm=false,coord=false): {}, locale=pl_PL,
timezone=Indian/Maldives
[junit4:junit4] 2> NOTE: FreeBSD 9.0-RELEASE amd64/Sun Microsystems Inc.
1.6.0_32 (64-bit)/cpus=16,threads=3,free=162999896,total=301596672
[junit4:junit4] 2> NOTE: All tests run in this JVM: [TestGroupingSearch,
DocumentBuilderTest, TestRTGBase, SpellCheckComponentTest,
TestSolrDeletionPolicy2, TestFiltering, BasicFunctionalityTest, TestPropInject,
FieldMutatingUpdateProcessorTest, DisMaxRequestHandlerTest, StatsComponentTest,
OverseerTest, TestCSVLoader, FileUtilsTest, TestWriterPerf, TestBinaryField,
SpellCheckCollatorTest, SampleTest, HighlighterTest, DebugComponentTest,
DirectUpdateHandlerTest, PrimUtilsTest, TestQueryTypes, TestPluginEnable,
TestSolrCoreProperties, SuggesterTSTTest, TestTrie, ReturnFieldsTest,
RequiredFieldsTest, TestCodecSupport, LegacyHTMLStripCharFilterTest,
TestRemoteStreaming, TestCSVResponseWriter, TestDocumentBuilder,
SolrInfoMBeanTest, TestOmitPositions, SystemInfoHandlerTest,
TestDFRSimilarityFactory, SuggesterTest, SoftAutoCommitTest, TestUpdate,
LukeRequestHandlerTest, TestQuerySenderListener, TestFaceting,
CircularListTest, TestPhraseSuggestions, SolrPluginUtilsTest,
TestSuggestSpellingConverter, URLClassifyProcessorTest,
StatelessScriptUpdateProcessorFactoryTest, TestSolrDeletionPolicy1,
TestReversedWildcardFilterFactory, ZkCLITest, TestFunctionQuery,
BasicDistributedZkTest, RecoveryZkTest]
[junit4:junit4] 2>
[junit4:junit4] Completed on J1 in 52.85s, 1 test, 1 failure <<< FAILURES!
[...truncated 346 lines...]
[...truncated 28450 lines...]
[...truncated 28450 lines...]
[...truncated 28450 lines...]
[...truncated 28450 lines...]
[...truncated 28435 lines...]
BUILD FAILED
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-4.x/checkout/build.xml:29:
The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-4.x/checkout/solr/build.xml:144:
The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-4.x/checkout/lucene/module-build.xml:62:
The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-4.x/checkout/lucene/common-build.xml:1095:
The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-tests-only-4.x/checkout/lucene/common-build.xml:790:
There were test failures: 223 suites, 901 tests, 1 failure, 6 ignored (1
assumption)
Total time: 28 minutes 39 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]
