[JENKINS] Lucene-Solr-NightlyTests-trunk - Build # 631 - Still Failing

[Apache Jenkins Server]

Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/631/

1 tests failed.
REGRESSION:  
org.apache.solr.handler.TestReplicationHandler.doTestReplicateAfterCoreReload

Error Message:
expected:<[{indexVersion=1411025056407,generation=2,filelist=[_3dj.doc, 
_3dj.fdt, _3dj.fdx, _3dj.fnm, _3dj.nvd, _3dj.nvm, _3dj.si, _3dj.tim, _3dj.tip, 
_6q2.doc, _6q2.fdt, _6q2.fdx, _6q2.fnm, _6q2.nvd, _6q2.nvm, _6q2.si, _6q2.tim, 
_6q2.tip, _a2k.doc, _a2k.fdt, _a2k.fdx, _a2k.fnm, _a2k.nvd, _a2k.nvm, _a2k.si, 
_a2k.tim, _a2k.tip, _df4.cfe, _df4.cfs, _df4.si, _grn.cfe, _grn.cfs, _grn.si, 
_k46.cfe, _k46.cfs, _k46.si, _nbo.doc, _nbo.fdt, _nbo.fdx, _nbo.fnm, _nbo.nvd, 
_nbo.nvm, _nbo.si, _nbo.tim, _nbo.tip, _nbp.cfe, _nbp.cfs, _nbp.si, _nbq.doc, 
_nbq.fdt, _nbq.fdx, _nbq.fnm, _nbq.nvd, _nbq.nvm, _nbq.si, _nbq.tim, _nbq.tip, 
_nbr.doc, _nbr.fdt, _nbr.fdx, _nbr.fnm, _nbr.nvd, _nbr.nvm, _nbr.si, _nbr.tim, 
_nbr.tip, _nbs.doc, _nbs.fdt, _nbs.fdx, _nbs.fnm, _nbs.nvd, _nbs.nvm, _nbs.si, 
_nbs.tim, _nbs.tip, _nbt.doc, _nbt.fdt, _nbt.fdx, _nbt.fnm, _nbt.nvd, _nbt.nvm, 
_nbt.si, _nbt.tim, _nbt.tip, _nbu.doc, _nbu.fdt, _nbu.fdx, _nbu.fnm, _nbu.nvd, 
_nbu.nvm, _nbu.si, _nbu.tim, _nbu.tip, _nbv.doc, _nbv.fdt, _nbv.fdx, _nbv.fnm, 
_nbv.nvd, _nbv.nvm, _nbv.si, _nbv.tim, _nbv.tip, _nbw.doc, _nbw.fdt, _nbw.fdx, 
_nbw.fnm, _nbw.nvd, _nbw.nvm, _nbw.si, _nbw.tim, _nbw.tip, _nbx.doc, _nbx.fdt, 
_nbx.fdx, _nbx.fnm, _nbx.nvd, _nbx.nvm, _nbx.si, _nbx.tim, _nbx.tip, _nby.doc, 
_nby.fdt, _nby.fdx, _nby.fnm, _nby.nvd, _nby.nvm, _nby.si, _nby.tim, _nby.tip, 
_nbz.doc, _nbz.fdt, _nbz.fdx, _nbz.fnm, _nbz.nvd, _nbz.nvm, _nbz.si, _nbz.tim, 
_nbz.tip, _nc0.doc, _nc0.fdt, _nc0.fdx, _nc0.fnm, _nc0.nvd, _nc0.nvm, _nc0.si, 
_nc0.tim, _nc0.tip, _nc1.doc, _nc1.fdt, _nc1.fdx, _nc1.fnm, _nc1.nvd, _nc1.nvm, 
_nc1.si, _nc1.tim, _nc1.tip, _nc2.doc, _nc2.fdt, _nc2.fdx, _nc2.fnm, _nc2.nvd, 
_nc2.nvm, _nc2.si, _nc2.tim, _nc2.tip, _nc3.doc, _nc3.fdt, _nc3.fdx, _nc3.fnm, 
_nc3.nvd, _nc3.nvm, _nc3.si, _nc3.tim, _nc3.tip, _nc4.doc, _nc4.fdt, _nc4.fdx, 
_nc4.fnm, _nc4.nvd, _nc4.nvm, _nc4.si, _nc4.tim, _nc4.tip, _nc5.doc, _nc5.fdt, 
_nc5.fdx, _nc5.fnm, _nc5.nvd, _nc5.nvm, _nc5.si, _nc5.tim, _nc5.tip, _nc6.doc, 
_nc6.fdt, _nc6.fdx, _nc6.fnm, _nc6.nvd, _nc6.nvm, _nc6.si, _nc6.tim, _nc6.tip, 
_nc8.doc, _nc8.fdt, _nc8.fdx, _nc8.fnm, _nc8.nvd, _nc8.nvm, _nc8.si, _nc8.tim, 
_nc8.tip, _nc9.doc, _nc9.fdt, _nc9.fdx, _nc9.fnm, _nc9.nvd, _nc9.nvm, _nc9.si, 
_nc9.tim, _nc9.tip, _nca.doc, _nca.fdt, _nca.fdx, _nca.fnm, _nca.nvd, _nca.nvm, 
_nca.si, _nca.tim, _nca.tip, _ncb.doc, _ncb.fdt, _ncb.fdx, _ncb.fnm, _ncb.nvd, 
_ncb.nvm, _ncb.si, _ncb.tim, _ncb.tip, segments_2]}]> but 
was:<[{indexVersion=1411025056407,generation=3,filelist=[_3dj.doc, _3dj.fdt, 
_3dj.fdx, _3dj.fnm, _3dj.nvd, _3dj.nvm, _3dj.si, _3dj.tim, _3dj.tip, _6q2.doc, 
_6q2.fdt, _6q2.fdx, _6q2.fnm, _6q2.nvd, _6q2.nvm, _6q2.si, _6q2.tim, _6q2.tip, 
_a2k.doc, _a2k.fdt, _a2k.fdx, _a2k.fnm, _a2k.nvd, _a2k.nvm, _a2k.si, _a2k.tim, 
_a2k.tip, _df4.cfe, _df4.cfs, _df4.si, _grn.cfe, _grn.cfs, _grn.si, _k46.cfe, 
_k46.cfs, _k46.si, _nc6.doc, _nc6.fdt, _nc6.fdx, _nc6.fnm, _nc6.nvd, _nc6.nvm, 
_nc6.si, _nc6.tim, _nc6.tip, _nc7.cfe, _nc7.cfs, _nc7.si, _nc8.doc, _nc8.fdt, 
_nc8.fdx, _nc8.fnm, _nc8.nvd, _nc8.nvm, _nc8.si, _nc8.tim, _nc8.tip, _nc9.doc, 
_nc9.fdt, _nc9.fdx, _nc9.fnm, _nc9.nvd, _nc9.nvm, _nc9.si, _nc9.tim, _nc9.tip, 
_nca.doc, _nca.fdt, _nca.fdx, _nca.fnm, _nca.nvd, _nca.nvm, _nca.si, _nca.tim, 
_nca.tip, _ncb.doc, _ncb.fdt, _ncb.fdx, _ncb.fnm, _ncb.nvd, _ncb.nvm, _ncb.si, 
_ncb.tim, _ncb.tip, segments_3]}, 
{indexVersion=1411025056407,generation=2,filelist=[_3dj.doc, _3dj.fdt, 
_3dj.fdx, _3dj.fnm, _3dj.nvd, _3dj.nvm, _3dj.si, _3dj.tim, _3dj.tip, _6q2.doc, 
_6q2.fdt, _6q2.fdx, _6q2.fnm, _6q2.nvd, _6q2.nvm, _6q2.si, _6q2.tim, _6q2.tip, 
_a2k.doc, _a2k.fdt, _a2k.fdx, _a2k.fnm, _a2k.nvd, _a2k.nvm, _a2k.si, _a2k.tim, 
_a2k.tip, _df4.cfe, _df4.cfs, _df4.si, _grn.cfe, _grn.cfs, _grn.si, _k46.cfe, 
_k46.cfs, _k46.si, _nbo.doc, _nbo.fdt, _nbo.fdx, _nbo.fnm, _nbo.nvd, _nbo.nvm, 
_nbo.si, _nbo.tim, _nbo.tip, _nbp.cfe, _nbp.cfs, _nbp.si, _nbq.doc, _nbq.fdt, 
_nbq.fdx, _nbq.fnm, _nbq.nvd, _nbq.nvm, _nbq.si, _nbq.tim, _nbq.tip, _nbr.doc, 
_nbr.fdt, _nbr.fdx, _nbr.fnm, _nbr.nvd, _nbr.nvm, _nbr.si, _nbr.tim, _nbr.tip, 
_nbs.doc, _nbs.fdt, _nbs.fdx, _nbs.fnm, _nbs.nvd, _nbs.nvm, _nbs.si, _nbs.tim, 
_nbs.tip, _nbt.doc, _nbt.fdt, _nbt.fdx, _nbt.fnm, _nbt.nvd, _nbt.nvm, _nbt.si, 
_nbt.tim, _nbt.tip, _nbu.doc, _nbu.fdt, _nbu.fdx, _nbu.fnm, _nbu.nvd, _nbu.nvm, 
_nbu.si, _nbu.tim, _nbu.tip, _nbv.doc, _nbv.fdt, _nbv.fdx, _nbv.fnm, _nbv.nvd, 
_nbv.nvm, _nbv.si, _nbv.tim, _nbv.tip, _nbw.doc, _nbw.fdt, _nbw.fdx, _nbw.fnm, 
_nbw.nvd, _nbw.nvm, _nbw.si, _nbw.tim, _nbw.tip, _nbx.doc, _nbx.fdt, _nbx.fdx, 
_nbx.fnm, _nbx.nvd, _nbx.nvm, _nbx.si, _nbx.tim, _nbx.tip, _nby.doc, _nby.fdt, 
_nby.fdx, _nby.fnm, _nby.nvd, _nby.nvm, _nby.si, _nby.tim, _nby.tip, _nbz.doc, 
_nbz.fdt, _nbz.fdx, _nbz.fnm, _nbz.nvd, _nbz.nvm, _nbz.si, _nbz.tim, _nbz.tip, 
_nc0.doc, _nc0.fdt, _nc0.fdx, _nc0.fnm, _nc0.nvd, _nc0.nvm, _nc0.si, _nc0.tim, 
_nc0.tip, _nc1.doc, _nc1.fdt, _nc1.fdx, _nc1.fnm, _nc1.nvd, _nc1.nvm, _nc1.si, 
_nc1.tim, _nc1.tip, _nc2.doc, _nc2.fdt, _nc2.fdx, _nc2.fnm, _nc2.nvd, _nc2.nvm, 
_nc2.si, _nc2.tim, _nc2.tip, _nc3.doc, _nc3.fdt, _nc3.fdx, _nc3.fnm, _nc3.nvd, 
_nc3.nvm, _nc3.si, _nc3.tim, _nc3.tip, _nc4.doc, _nc4.fdt, _nc4.fdx, _nc4.fnm, 
_nc4.nvd, _nc4.nvm, _nc4.si, _nc4.tim, _nc4.tip, _nc5.doc, _nc5.fdt, _nc5.fdx, 
_nc5.fnm, _nc5.nvd, _nc5.nvm, _nc5.si, _nc5.tim, _nc5.tip, _nc6.doc, _nc6.fdt, 
_nc6.fdx, _nc6.fnm, _nc6.nvd, _nc6.nvm, _nc6.si, _nc6.tim, _nc6.tip, _nc8.doc, 
_nc8.fdt, _nc8.fdx, _nc8.fnm, _nc8.nvd, _nc8.nvm, _nc8.si, _nc8.tim, _nc8.tip, 
_nc9.doc, _nc9.fdt, _nc9.fdx, _nc9.fnm, _nc9.nvd, _nc9.nvm, _nc9.si, _nc9.tim, 
_nc9.tip, _nca.doc, _nca.fdt, _nca.fdx, _nca.fnm, _nca.nvd, _nca.nvm, _nca.si, 
_nca.tim, _nca.tip, _ncb.doc, _ncb.fdt, _ncb.fdx, _ncb.fnm, _ncb.nvd, _ncb.nvm, 
_ncb.si, _ncb.tim, _ncb.tip, segments_2]}]>

Stack Trace:
java.lang.AssertionError: 
expected:<[{indexVersion=1411025056407,generation=2,filelist=[_3dj.doc, 
_3dj.fdt, _3dj.fdx, _3dj.fnm, _3dj.nvd, _3dj.nvm, _3dj.si, _3dj.tim, _3dj.tip, 
_6q2.doc, _6q2.fdt, _6q2.fdx, _6q2.fnm, _6q2.nvd, _6q2.nvm, _6q2.si, _6q2.tim, 
_6q2.tip, _a2k.doc, _a2k.fdt, _a2k.fdx, _a2k.fnm, _a2k.nvd, _a2k.nvm, _a2k.si, 
_a2k.tim, _a2k.tip, _df4.cfe, _df4.cfs, _df4.si, _grn.cfe, _grn.cfs, _grn.si, 
_k46.cfe, _k46.cfs, _k46.si, _nbo.doc, _nbo.fdt, _nbo.fdx, _nbo.fnm, _nbo.nvd, 
_nbo.nvm, _nbo.si, _nbo.tim, _nbo.tip, _nbp.cfe, _nbp.cfs, _nbp.si, _nbq.doc, 
_nbq.fdt, _nbq.fdx, _nbq.fnm, _nbq.nvd, _nbq.nvm, _nbq.si, _nbq.tim, _nbq.tip, 
_nbr.doc, _nbr.fdt, _nbr.fdx, _nbr.fnm, _nbr.nvd, _nbr.nvm, _nbr.si, _nbr.tim, 
_nbr.tip, _nbs.doc, _nbs.fdt, _nbs.fdx, _nbs.fnm, _nbs.nvd, _nbs.nvm, _nbs.si, 
_nbs.tim, _nbs.tip, _nbt.doc, _nbt.fdt, _nbt.fdx, _nbt.fnm, _nbt.nvd, _nbt.nvm, 
_nbt.si, _nbt.tim, _nbt.tip, _nbu.doc, _nbu.fdt, _nbu.fdx, _nbu.fnm, _nbu.nvd, 
_nbu.nvm, _nbu.si, _nbu.tim, _nbu.tip, _nbv.doc, _nbv.fdt, _nbv.fdx, _nbv.fnm, 
_nbv.nvd, _nbv.nvm, _nbv.si, _nbv.tim, _nbv.tip, _nbw.doc, _nbw.fdt, _nbw.fdx, 
_nbw.fnm, _nbw.nvd, _nbw.nvm, _nbw.si, _nbw.tim, _nbw.tip, _nbx.doc, _nbx.fdt, 
_nbx.fdx, _nbx.fnm, _nbx.nvd, _nbx.nvm, _nbx.si, _nbx.tim, _nbx.tip, _nby.doc, 
_nby.fdt, _nby.fdx, _nby.fnm, _nby.nvd, _nby.nvm, _nby.si, _nby.tim, _nby.tip, 
_nbz.doc, _nbz.fdt, _nbz.fdx, _nbz.fnm, _nbz.nvd, _nbz.nvm, _nbz.si, _nbz.tim, 
_nbz.tip, _nc0.doc, _nc0.fdt, _nc0.fdx, _nc0.fnm, _nc0.nvd, _nc0.nvm, _nc0.si, 
_nc0.tim, _nc0.tip, _nc1.doc, _nc1.fdt, _nc1.fdx, _nc1.fnm, _nc1.nvd, _nc1.nvm, 
_nc1.si, _nc1.tim, _nc1.tip, _nc2.doc, _nc2.fdt, _nc2.fdx, _nc2.fnm, _nc2.nvd, 
_nc2.nvm, _nc2.si, _nc2.tim, _nc2.tip, _nc3.doc, _nc3.fdt, _nc3.fdx, _nc3.fnm, 
_nc3.nvd, _nc3.nvm, _nc3.si, _nc3.tim, _nc3.tip, _nc4.doc, _nc4.fdt, _nc4.fdx, 
_nc4.fnm, _nc4.nvd, _nc4.nvm, _nc4.si, _nc4.tim, _nc4.tip, _nc5.doc, _nc5.fdt, 
_nc5.fdx, _nc5.fnm, _nc5.nvd, _nc5.nvm, _nc5.si, _nc5.tim, _nc5.tip, _nc6.doc, 
_nc6.fdt, _nc6.fdx, _nc6.fnm, _nc6.nvd, _nc6.nvm, _nc6.si, _nc6.tim, _nc6.tip, 
_nc8.doc, _nc8.fdt, _nc8.fdx, _nc8.fnm, _nc8.nvd, _nc8.nvm, _nc8.si, _nc8.tim, 
_nc8.tip, _nc9.doc, _nc9.fdt, _nc9.fdx, _nc9.fnm, _nc9.nvd, _nc9.nvm, _nc9.si, 
_nc9.tim, _nc9.tip, _nca.doc, _nca.fdt, _nca.fdx, _nca.fnm, _nca.nvd, _nca.nvm, 
_nca.si, _nca.tim, _nca.tip, _ncb.doc, _ncb.fdt, _ncb.fdx, _ncb.fnm, _ncb.nvd, 
_ncb.nvm, _ncb.si, _ncb.tim, _ncb.tip, segments_2]}]> but 
was:<[{indexVersion=1411025056407,generation=3,filelist=[_3dj.doc, _3dj.fdt, 
_3dj.fdx, _3dj.fnm, _3dj.nvd, _3dj.nvm, _3dj.si, _3dj.tim, _3dj.tip, _6q2.doc, 
_6q2.fdt, _6q2.fdx, _6q2.fnm, _6q2.nvd, _6q2.nvm, _6q2.si, _6q2.tim, _6q2.tip, 
_a2k.doc, _a2k.fdt, _a2k.fdx, _a2k.fnm, _a2k.nvd, _a2k.nvm, _a2k.si, _a2k.tim, 
_a2k.tip, _df4.cfe, _df4.cfs, _df4.si, _grn.cfe, _grn.cfs, _grn.si, _k46.cfe, 
_k46.cfs, _k46.si, _nc6.doc, _nc6.fdt, _nc6.fdx, _nc6.fnm, _nc6.nvd, _nc6.nvm, 
_nc6.si, _nc6.tim, _nc6.tip, _nc7.cfe, _nc7.cfs, _nc7.si, _nc8.doc, _nc8.fdt, 
_nc8.fdx, _nc8.fnm, _nc8.nvd, _nc8.nvm, _nc8.si, _nc8.tim, _nc8.tip, _nc9.doc, 
_nc9.fdt, _nc9.fdx, _nc9.fnm, _nc9.nvd, _nc9.nvm, _nc9.si, _nc9.tim, _nc9.tip, 
_nca.doc, _nca.fdt, _nca.fdx, _nca.fnm, _nca.nvd, _nca.nvm, _nca.si, _nca.tim, 
_nca.tip, _ncb.doc, _ncb.fdt, _ncb.fdx, _ncb.fnm, _ncb.nvd, _ncb.nvm, _ncb.si, 
_ncb.tim, _ncb.tip, segments_3]}, 
{indexVersion=1411025056407,generation=2,filelist=[_3dj.doc, _3dj.fdt, 
_3dj.fdx, _3dj.fnm, _3dj.nvd, _3dj.nvm, _3dj.si, _3dj.tim, _3dj.tip, _6q2.doc, 
_6q2.fdt, _6q2.fdx, _6q2.fnm, _6q2.nvd, _6q2.nvm, _6q2.si, _6q2.tim, _6q2.tip, 
_a2k.doc, _a2k.fdt, _a2k.fdx, _a2k.fnm, _a2k.nvd, _a2k.nvm, _a2k.si, _a2k.tim, 
_a2k.tip, _df4.cfe, _df4.cfs, _df4.si, _grn.cfe, _grn.cfs, _grn.si, _k46.cfe, 
_k46.cfs, _k46.si, _nbo.doc, _nbo.fdt, _nbo.fdx, _nbo.fnm, _nbo.nvd, _nbo.nvm, 
_nbo.si, _nbo.tim, _nbo.tip, _nbp.cfe, _nbp.cfs, _nbp.si, _nbq.doc, _nbq.fdt, 
_nbq.fdx, _nbq.fnm, _nbq.nvd, _nbq.nvm, _nbq.si, _nbq.tim, _nbq.tip, _nbr.doc, 
_nbr.fdt, _nbr.fdx, _nbr.fnm, _nbr.nvd, _nbr.nvm, _nbr.si, _nbr.tim, _nbr.tip, 
_nbs.doc, _nbs.fdt, _nbs.fdx, _nbs.fnm, _nbs.nvd, _nbs.nvm, _nbs.si, _nbs.tim, 
_nbs.tip, _nbt.doc, _nbt.fdt, _nbt.fdx, _nbt.fnm, _nbt.nvd, _nbt.nvm, _nbt.si, 
_nbt.tim, _nbt.tip, _nbu.doc, _nbu.fdt, _nbu.fdx, _nbu.fnm, _nbu.nvd, _nbu.nvm, 
_nbu.si, _nbu.tim, _nbu.tip, _nbv.doc, _nbv.fdt, _nbv.fdx, _nbv.fnm, _nbv.nvd, 
_nbv.nvm, _nbv.si, _nbv.tim, _nbv.tip, _nbw.doc, _nbw.fdt, _nbw.fdx, _nbw.fnm, 
_nbw.nvd, _nbw.nvm, _nbw.si, _nbw.tim, _nbw.tip, _nbx.doc, _nbx.fdt, _nbx.fdx, 
_nbx.fnm, _nbx.nvd, _nbx.nvm, _nbx.si, _nbx.tim, _nbx.tip, _nby.doc, _nby.fdt, 
_nby.fdx, _nby.fnm, _nby.nvd, _nby.nvm, _nby.si, _nby.tim, _nby.tip, _nbz.doc, 
_nbz.fdt, _nbz.fdx, _nbz.fnm, _nbz.nvd, _nbz.nvm, _nbz.si, _nbz.tim, _nbz.tip, 
_nc0.doc, _nc0.fdt, _nc0.fdx, _nc0.fnm, _nc0.nvd, _nc0.nvm, _nc0.si, _nc0.tim, 
_nc0.tip, _nc1.doc, _nc1.fdt, _nc1.fdx, _nc1.fnm, _nc1.nvd, _nc1.nvm, _nc1.si, 
_nc1.tim, _nc1.tip, _nc2.doc, _nc2.fdt, _nc2.fdx, _nc2.fnm, _nc2.nvd, _nc2.nvm, 
_nc2.si, _nc2.tim, _nc2.tip, _nc3.doc, _nc3.fdt, _nc3.fdx, _nc3.fnm, _nc3.nvd, 
_nc3.nvm, _nc3.si, _nc3.tim, _nc3.tip, _nc4.doc, _nc4.fdt, _nc4.fdx, _nc4.fnm, 
_nc4.nvd, _nc4.nvm, _nc4.si, _nc4.tim, _nc4.tip, _nc5.doc, _nc5.fdt, _nc5.fdx, 
_nc5.fnm, _nc5.nvd, _nc5.nvm, _nc5.si, _nc5.tim, _nc5.tip, _nc6.doc, _nc6.fdt, 
_nc6.fdx, _nc6.fnm, _nc6.nvd, _nc6.nvm, _nc6.si, _nc6.tim, _nc6.tip, _nc8.doc, 
_nc8.fdt, _nc8.fdx, _nc8.fnm, _nc8.nvd, _nc8.nvm, _nc8.si, _nc8.tim, _nc8.tip, 
_nc9.doc, _nc9.fdt, _nc9.fdx, _nc9.fnm, _nc9.nvd, _nc9.nvm, _nc9.si, _nc9.tim, 
_nc9.tip, _nca.doc, _nca.fdt, _nca.fdx, _nca.fnm, _nca.nvd, _nca.nvm, _nca.si, 
_nca.tim, _nca.tip, _ncb.doc, _ncb.fdt, _ncb.fdx, _ncb.fnm, _ncb.nvd, _ncb.nvm, 
_ncb.si, _ncb.tim, _ncb.tip, segments_2]}]>
        at 
__randomizedtesting.SeedInfo.seed([47FD3A04C59217E5:622A2134B5DA19E6]: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:147)
        at 
org.apache.solr.handler.TestReplicationHandler.doTestReplicateAfterCoreReload(TestReplicationHandler.java:1191)
        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:606)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
        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.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 12550 lines...]
   [junit4] Suite: org.apache.solr.handler.TestReplicationHandler
   [junit4]   2> Creating dataDir: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/init-core-data-001
   [junit4]   2> 1345108 T7579 oas.SolrTestCaseJ4.setUp ###Starting 
testEmptyCommits
   [junit4]   2> 1345125 T7579 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1345129 T7579 oejs.AbstractConnector.doStart Started 
SocketConnector@127.0.0.1:39022
   [junit4]   2> 1345130 T7579 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init()
   [junit4]   2> 1345130 T7579 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 1345130 T7579 oasc.SolrResourceLoader.locateSolrHome using 
system property solr.solr.home: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001
   [junit4]   2> 1345131 T7579 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/'
   [junit4]   2> 1345176 T7579 oasc.ConfigSolr.fromFile Loading container 
configuration from 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/solr.xml
   [junit4]   2> 1345258 T7579 oasc.CoreContainer.<init> New CoreContainer 
306970080
   [junit4]   2> 1345259 T7579 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/]
   [junit4]   2> 1345260 T7579 oashc.HttpShardHandlerFactory.getParameter 
Setting socketTimeout to: 90000
   [junit4]   2> 1345260 T7579 oashc.HttpShardHandlerFactory.getParameter 
Setting urlScheme to: 
   [junit4]   2> 1345261 T7579 oashc.HttpShardHandlerFactory.getParameter 
Setting connTimeout to: 15000
   [junit4]   2> 1345261 T7579 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1345261 T7579 oashc.HttpShardHandlerFactory.getParameter 
Setting corePoolSize to: 0
   [junit4]   2> 1345262 T7579 oashc.HttpShardHandlerFactory.getParameter 
Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1345262 T7579 oashc.HttpShardHandlerFactory.getParameter 
Setting maxThreadIdleTime to: 5
   [junit4]   2> 1345262 T7579 oashc.HttpShardHandlerFactory.getParameter 
Setting sizeOfQueue to: -1
   [junit4]   2> 1345263 T7579 oashc.HttpShardHandlerFactory.getParameter 
Setting fairnessPolicy to: false
   [junit4]   2> 1345263 T7579 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=false
   [junit4]   2> 1345264 T7579 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1345264 T7579 oasl.LogWatcher.newRegisteredLogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1345264 T7579 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 1345270 T7589 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/'
   [junit4]   2> 1345315 T7589 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: 5.0.0
   [junit4]   2> 1345356 T7589 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 1345357 T7589 oass.IndexSchema.readSchema Reading Solr Schema 
from schema.xml
   [junit4]   2> 1345363 T7589 oass.IndexSchema.readSchema [collection1] Schema 
name=test
   [junit4]   2> 1345380 T7589 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1345381 T7589 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/
   [junit4]   2> 1345381 T7589 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1345381 T7589 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/,
 
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/data/
   [junit4]   2> 1345382 T7589 oasc.SolrCore.<init> JMX monitoring not detected 
for core: collection1
   [junit4]   2> 1345382 T7589 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/data
   [junit4]   2> 1345382 T7589 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/data/index/
   [junit4]   2> 1345383 T7589 oasc.SolrCore.initIndex WARN [collection1] Solr 
index directory 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 1345383 T7589 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/data/index
   [junit4]   2> 1345383 T7589 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: 
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=3, 
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, 
calibrateSizeByDeletes=true, maxMergeDocs=2147483647, 
maxCFSSegmentSizeMB=1.8408203125, noCFSRatio=0.4518077632545774]
   [junit4]   2> 1345384 T7589 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-1bd1d85)),segFN=segments_1,generation=1}
   [junit4]   2> 1345384 T7589 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1345385 T7589 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1345386 T7589 oasc.RequestHandlers.initHandlersFromConfig 
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1345386 T7589 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1345387 T7589 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1345387 T7589 oasc.RequestHandlers.initHandlersFromConfig 
created standard: solr.StandardRequestHandler
   [junit4]   2> 1345387 T7589 oasc.RequestHandlers.initHandlersFromConfig 
created /replication: solr.ReplicationHandler
   [junit4]   2> 1345387 T7589 oasc.RequestHandlers.initHandlersFromConfig 
created defaults: solr.StandardRequestHandler
   [junit4]   2> 1345388 T7589 oasc.RequestHandlers.initHandlersFromConfig 
adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 1345388 T7589 oasc.RequestHandlers.initHandlersFromConfig 
created lazy: solr.StandardRequestHandler
   [junit4]   2> 1345388 T7589 oasc.RequestHandlers.initHandlersFromConfig 
created /update: solr.UpdateRequestHandler
   [junit4]   2> 1345406 T7589 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1345408 T7589 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1345410 T7589 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1345412 T7589 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1345414 T7589 oasu.CommitTracker.<init> Hard AutoCommit: 
disabled
   [junit4]   2> 1345414 T7589 oasu.CommitTracker.<init> Soft AutoCommit: 
disabled
   [junit4]   2> 1345415 T7589 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=18, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=1.1943359375, 
floorSegmentMB=1.2939453125, forceMergeDeletesPctAllowed=15.740548798951462, 
segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.2759470023421645
   [junit4]   2> 1345415 T7589 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-1bd1d85)),segFN=segments_1,generation=1}
   [junit4]   2> 1345416 T7589 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1345416 T7589 oass.SolrIndexSearcher.<init> Opening 
Searcher@22a4ac95[collection1] main
   [junit4]   2> 1345416 T7589 
oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage 
initialized to use dir: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/conf
   [junit4]   2> 1345417 T7589 oasr.RestManager.init Initializing RestManager 
with initArgs: 
{storageDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/conf}
   [junit4]   2> 1345417 T7589 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using 
file:dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/conf
   [junit4]   2> 1345418 T7589 oasr.ManagedResource.reloadFromStorage WARN No 
stored data found for /rest/managed
   [junit4]   2> 1345418 T7589 oasr.ManagedResourceStorage$JsonStorage.store 
Saved JSON object to path _rest_managed.json using 
file:dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/conf
   [junit4]   2> 1345419 T7589 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 1345419 T7589 oash.ReplicationHandler.inform Replication 
enabled for following config files: schema.xml,xslt/dummy.xsl
   [junit4]   2> 1345419 T7589 oash.ReplicationHandler.inform Commits will be 
reserved for  10000
   [junit4]   2> 1345420 T7590 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@22a4ac95[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 1345420 T7589 oasc.CoreContainer.registerCore registering 
core: collection1
   [junit4]   2> 1345420 T7579 oass.SolrDispatchFilter.init 
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3
   [junit4]   2> 1345421 T7579 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init() done
   [junit4]   2> 1345432 T7579 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 1345435 T7579 oejs.AbstractConnector.doStart Started 
SocketConnector@127.0.0.1:39023
   [junit4]   2> 1345435 T7579 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init()
   [junit4]   2> 1345436 T7579 oasc.SolrResourceLoader.locateSolrHome JNDI not 
configured for solr (NoInitialContextEx)
   [junit4]   2> 1345436 T7579 oasc.SolrResourceLoader.locateSolrHome using 
system property solr.solr.home: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002
   [junit4]   2> 1345436 T7579 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/'
   [junit4]   2> 1345453 T7579 oasc.ConfigSolr.fromFile Loading container 
configuration from 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/solr.xml
   [junit4]   2> 1345520 T7579 oasc.CoreContainer.<init> New CoreContainer 
438540849
   [junit4]   2> 1345520 T7579 oasc.CoreContainer.load Loading cores into 
CoreContainer 
[instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/]
   [junit4]   2> 1345522 T7579 oashc.HttpShardHandlerFactory.getParameter 
Setting socketTimeout to: 90000
   [junit4]   2> 1345522 T7579 oashc.HttpShardHandlerFactory.getParameter 
Setting urlScheme to: 
   [junit4]   2> 1345522 T7579 oashc.HttpShardHandlerFactory.getParameter 
Setting connTimeout to: 15000
   [junit4]   2> 1345523 T7579 oashc.HttpShardHandlerFactory.getParameter 
Setting maxConnectionsPerHost to: 20
   [junit4]   2> 1345523 T7579 oashc.HttpShardHandlerFactory.getParameter 
Setting corePoolSize to: 0
   [junit4]   2> 1345523 T7579 oashc.HttpShardHandlerFactory.getParameter 
Setting maximumPoolSize to: 2147483647
   [junit4]   2> 1345524 T7579 oashc.HttpShardHandlerFactory.getParameter 
Setting maxThreadIdleTime to: 5
   [junit4]   2> 1345524 T7579 oashc.HttpShardHandlerFactory.getParameter 
Setting sizeOfQueue to: -1
   [junit4]   2> 1345524 T7579 oashc.HttpShardHandlerFactory.getParameter 
Setting fairnessPolicy to: false
   [junit4]   2> 1345525 T7579 oasu.UpdateShardHandler.<init> Creating 
UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=false
   [junit4]   2> 1345525 T7579 oasl.LogWatcher.createWatcher SLF4J impl is 
org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1345526 T7579 oasl.LogWatcher.newRegisteredLogWatcher 
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1345526 T7579 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 1345531 T7601 oasc.SolrResourceLoader.<init> new 
SolrResourceLoader for directory: 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/'
   [junit4]   2> 1345576 T7601 oasc.SolrConfig.<init> Using Lucene 
MatchVersion: 5.0.0
   [junit4]   2> 1345616 T7601 oasc.SolrConfig.<init> Loaded SolrConfig: 
solrconfig.xml
   [junit4]   2> 1345617 T7601 oass.IndexSchema.readSchema Reading Solr Schema 
from schema.xml
   [junit4]   2> 1345624 T7601 oass.IndexSchema.readSchema [collection1] Schema 
name=test
   [junit4]   2> 1345636 T7601 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 1345637 T7601 oasc.CoreContainer.create Creating SolrCore 
'collection1' using configuration from instancedir 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/
   [junit4]   2> 1345637 T7601 oasc.SolrCore.initDirectoryFactory 
org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1345638 T7601 oasc.SolrCore.<init> [collection1] Opening new 
SolrCore at 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/,
 
dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/
   [junit4]   2> 1345638 T7601 oasc.SolrCore.<init> JMX monitoring not detected 
for core: collection1
   [junit4]   2> 1345639 T7601 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data
   [junit4]   2> 1345639 T7601 oasc.SolrCore.getNewIndexDir New index directory 
detected: old=null 
new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/index/
   [junit4]   2> 1345639 T7601 oasc.SolrCore.initIndex WARN [collection1] Solr 
index directory 
'/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/index'
 doesn't exist. Creating new index...
   [junit4]   2> 1345640 T7601 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/index
   [junit4]   2> 1345641 T7601 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: 
[LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=3, 
maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, 
calibrateSizeByDeletes=true, maxMergeDocs=2147483647, 
maxCFSSegmentSizeMB=1.8408203125, noCFSRatio=0.4518077632545774]
   [junit4]   2> 1345641 T7601 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-68c88421)),segFN=segments_1,generation=1}
   [junit4]   2> 1345642 T7601 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1345643 T7601 oasc.SolrCore.loadUpdateProcessorChains no 
updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1345643 T7601 oasc.RequestHandlers.initHandlersFromConfig 
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1345643 T7601 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1345644 T7601 oasc.RequestHandlers.initHandlersFromConfig 
created /update/json: org.apache.solr.handler.UpdateRequestHandler
   [junit4]   2> 1345644 T7601 oasc.RequestHandlers.initHandlersFromConfig 
created standard: solr.StandardRequestHandler
   [junit4]   2> 1345644 T7601 oasc.RequestHandlers.initHandlersFromConfig 
created defaults: solr.StandardRequestHandler
   [junit4]   2> 1345644 T7601 oasc.RequestHandlers.initHandlersFromConfig 
adding lazy requestHandler: solr.StandardRequestHandler
   [junit4]   2> 1345645 T7601 oasc.RequestHandlers.initHandlersFromConfig 
created lazy: solr.StandardRequestHandler
   [junit4]   2> 1345645 T7601 oasc.RequestHandlers.initHandlersFromConfig 
created /update: solr.UpdateRequestHandler
   [junit4]   2> 1345645 T7601 oasc.RequestHandlers.initHandlersFromConfig 
created /replication: solr.ReplicationHandler
   [junit4]   2> 1345647 T7601 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1345650 T7601 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1345651 T7601 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1345653 T7601 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 1345655 T7601 oasu.CommitTracker.<init> Hard AutoCommit: 
disabled
   [junit4]   2> 1345656 T7601 oasu.CommitTracker.<init> Soft AutoCommit: 
disabled
   [junit4]   2> 1345656 T7601 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=18, maxMergeAtOnceExplicit=34, maxMergedSegmentMB=1.1943359375, 
floorSegmentMB=1.2939453125, forceMergeDeletesPctAllowed=15.740548798951462, 
segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.2759470023421645
   [junit4]   2> 1345657 T7601 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-68c88421)),segFN=segments_1,generation=1}
   [junit4]   2> 1345657 T7601 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 1
   [junit4]   2> 1345657 T7601 oass.SolrIndexSearcher.<init> Opening 
Searcher@64a783c8[collection1] main
   [junit4]   2> 1345658 T7601 
oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage 
initialized to use dir: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/conf
   [junit4]   2> 1345658 T7601 oasr.RestManager.init Initializing RestManager 
with initArgs: 
{storageDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/conf}
   [junit4]   2> 1345658 T7601 oasr.ManagedResourceStorage.load Reading 
_rest_managed.json using 
file:dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/conf
   [junit4]   2> 1345659 T7601 oasr.ManagedResource.reloadFromStorage WARN No 
stored data found for /rest/managed
   [junit4]   2> 1345660 T7601 oasr.ManagedResourceStorage$JsonStorage.store 
Saved JSON object to path _rest_managed.json using 
file:dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/conf
   [junit4]   2> 1345660 T7601 oasr.RestManager.init Initializing 0 registered 
ManagedResources
   [junit4]   2> 1345666 T7601 oash.SnapPuller.startExecutorService Poll 
Scheduled at an interval of 1000ms
   [junit4]   2> 1345666 T7602 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@64a783c8[collection1] 
main{UninvertingDirectoryReader()}
   [junit4]   2> 1345666 T7601 oasc.CoreContainer.registerCore registering 
core: collection1
   [junit4]   2> 1345668 T7579 oass.SolrDispatchFilter.init 
user.dir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3
   [junit4]   2> 1345668 T7579 oass.SolrDispatchFilter.init 
SolrDispatchFilter.init() done
   [junit4]   2> ASYNC  NEW_CORE C494 name=collection1 
org.apache.solr.core.SolrCore@2b35a15c
   [junit4]   2> 1345671 T7582 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/select params={wt=javabin&version=2&sort=id+desc&q=*:*} 
hits=0 status=0 QTime=1 
   [junit4]   2> 1345675 T7582 C494 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 
0 1
   [junit4]   2> 1345678 T7582 C494 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1345680 T7582 C494 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-1bd1d85)),segFN=segments_1,generation=1}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-1bd1d85)),segFN=segments_2,generation=2}
   [junit4]   2> 1345681 T7582 C494 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 2
   [junit4]   2> 1345683 T7582 C494 oass.SolrIndexSearcher.<init> Opening 
Searcher@5ca6ad12[collection1] main
   [junit4]   2> 1345683 T7582 C494 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1345683 T7590 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@5ca6ad12[collection1] 
main{UninvertingDirectoryReader(Uninverting(_0(5.0.0):C1))}
   [junit4]   2> 1345684 T7582 C494 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update 
params={wt=javabin&version=2&commit=true} {commit=} 0 6
   [junit4]   2> 1345717 T7605 oash.SnapPuller.<init> WARN 'masterUrl' must be 
specified without the /replication suffix
   [junit4]   2> 1345718 T7605 oash.SnapPuller.<init>  No value set for 
'pollInterval'. Timer Task not started.
   [junit4]   2> 1345720 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0 
   [junit4]   2> 1345721 T7605 oash.SnapPuller.fetchLatestIndex Master's 
generation: 2
   [junit4]   2> 1345721 T7605 oash.SnapPuller.fetchLatestIndex Slave's 
generation: 1
   [junit4]   2> 1345721 T7605 oash.SnapPuller.fetchLatestIndex Starting 
replication process
   [junit4]   2> 1345731 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&generation=2&command=filelist} 
status=0 QTime=8 
   [junit4]   2> 1345732 T7605 oash.SnapPuller.fetchLatestIndex Number of files 
in latest index in master: 10
   [junit4]   2> 1345733 T7605 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/index.20140918091447777
   [junit4]   2> 1345733 T7605 oasu.DefaultSolrCoreState.closeIndexWriter 
Closing IndexWriter...
   [junit4]   2> 1345733 T7605 oasu.DefaultSolrCoreState.closeIndexWriter 
Waiting until IndexWriter is unused... core=collection1
   [junit4]   2> 1345734 T7605 oasu.DefaultSolrCoreState.closeIndexWriter 
Rollback old IndexWriter... core=collection1
   [junit4]   2> 1345735 T7605 oash.SnapPuller.fetchLatestIndex Starting 
download to MockDirectoryWrapper(RAMDirectory(id=lucene-2edfb8ae)) 
fullCopy=false
   [junit4]   2> 1345737 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_0.doc&qt=/replication&wt=filestream&generation=2&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345741 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_0.tip&qt=/replication&wt=filestream&generation=2&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345745 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_0.tim&qt=/replication&wt=filestream&generation=2&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345748 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_0.nvd&qt=/replication&wt=filestream&generation=2&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345751 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=segments_2&qt=/replication&wt=filestream&generation=2&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345755 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_0.nvm&qt=/replication&wt=filestream&generation=2&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345758 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_0.si&qt=/replication&wt=filestream&generation=2&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345761 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_0.fdx&qt=/replication&wt=filestream&generation=2&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345764 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_0.fnm&qt=/replication&wt=filestream&generation=2&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345767 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_0.fdt&qt=/replication&wt=filestream&generation=2&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345768 T7605 oash.SnapPuller.fetchLatestIndex Total time 
taken for download : 0 secs
   [junit4]   2> 1345770 T7605 oasu.DefaultSolrCoreState.openIndexWriter 
Creating new IndexWriter...
   [junit4]   2> 1345770 T7605 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=16, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 1345771 T7605 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-68c88421)),segFN=segments_1,generation=1}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-68c88421)),segFN=segments_2,generation=2}
   [junit4]   2> 1345772 T7605 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 2
   [junit4]   2> 1345773 T7605 oasu.DefaultSolrCoreState.openIndexWriter New 
IndexWriter is ready to be used.
   [junit4]   2> 1345774 T7605 oass.SolrIndexSearcher.<init> Opening 
Searcher@3c36ef9d[collection1] main
   [junit4]   2> 1345774 T7602 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@3c36ef9d[collection1] 
main{UninvertingDirectoryReader(Uninverting(_0(5.0.0):C1))}
   [junit4]   2> 1345774 T7605 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/index.20140918091447777
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/index.20140918091447777;done=true>>]
   [junit4]   2> 1345774 T7605 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/index.20140918091447777
   [junit4]   2> ASYNC  NEW_CORE C495 name=collection1 
org.apache.solr.core.SolrCore@7ddd198d
   [junit4]   2> 1345775 T7594 C495 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wait=true&command=fetchindex&masterUrl=http://127.0.0.1:39022/solr/replication}
 status=0 QTime=59 
   [junit4]   2> 1345782 T7595 C495 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/select 
params={wt=javabin&version=2&sort=id+desc&q=name:empty1} hits=1 status=0 
QTime=1 
   [junit4]   2> 1345783 T7579 oash.TestReplicationHandler.rQuery Waited for 
0ms and found 1 docs
   [junit4]   2> 1345786 T7582 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&_trace=getDetails&command=details} 
status=0 QTime=0 
   [junit4]   2> 1345789 T7582 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&_trace=getDetails&command=details} 
status=0 QTime=0 
   [junit4]   2> 1345795 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details&slave=false} 
status=0 QTime=0 
   [junit4]   2> 1345796 T7595 C495 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&_trace=getDetails&command=details} 
status=0 QTime=4 
   [junit4]   2> 1345799 T7582 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&_trace=assertVersions&command=indexversion}
 status=0 QTime=0 
   [junit4]   2> 1345802 T7595 C495 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&_trace=assertVersions&command=indexversion}
 status=0 QTime=0 
   [junit4]   2> 1345805 T7582 C494 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1345806 T7582 C494 oasu.DirectUpdateHandler2.commit No 
uncommitted changes. Skipping IW.commit.
   [junit4]   2> 1345806 T7582 C494 oasc.SolrCore.openNewSearcher 
SolrIndexSearcher has not changed - not re-opening: 
org.apache.solr.search.SolrIndexSearcher
   [junit4]   2> 1345806 T7582 C494 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1345807 T7582 C494 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update 
params={wt=javabin&version=2&commit=true} {commit=} 0 2
   [junit4]   2> 1345808 T7608 oash.SnapPuller.<init> WARN 'masterUrl' must be 
specified without the /replication suffix
   [junit4]   2> 1345809 T7608 oash.SnapPuller.<init>  No value set for 
'pollInterval'. Timer Task not started.
   [junit4]   2> 1345812 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0 
   [junit4]   2> 1345813 T7608 oash.SnapPuller.fetchLatestIndex Slave in sync 
with master.
   [junit4]   2> 1345813 T7594 C495 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wait=true&command=fetchindex&masterUrl=http://127.0.0.1:39022/solr/replication}
 status=0 QTime=5 
   [junit4]   2> 1345817 T7582 C494 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 
0 1
   [junit4]   2> 1345819 T7582 C494 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1345821 T7582 C494 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-1bd1d85)),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-1bd1d85)),segFN=segments_3,generation=3}
   [junit4]   2> 1345821 T7582 C494 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 3
   [junit4]   2> 1345822 T7582 C494 oass.SolrIndexSearcher.<init> Opening 
Searcher@60ec495e[collection1] main
   [junit4]   2> 1345823 T7582 C494 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1345823 T7590 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@60ec495e[collection1] 
main{UninvertingDirectoryReader(Uninverting(_0(5.0.0):C1) 
Uninverting(_1(5.0.0):C1))}
   [junit4]   2> 1345824 T7582 C494 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update 
params={wt=javabin&version=2&commit=true} {commit=} 0 5
   [junit4]   2> 1345825 T7609 oash.SnapPuller.<init> WARN 'masterUrl' must be 
specified without the /replication suffix
   [junit4]   2> 1345825 T7609 oash.SnapPuller.<init>  No value set for 
'pollInterval'. Timer Task not started.
   [junit4]   2> 1345828 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0 
   [junit4]   2> 1345829 T7609 oash.SnapPuller.fetchLatestIndex Master's 
generation: 3
   [junit4]   2> 1345829 T7609 oash.SnapPuller.fetchLatestIndex Slave's 
generation: 2
   [junit4]   2> 1345829 T7609 oash.SnapPuller.fetchLatestIndex Starting 
replication process
   [junit4]   2> 1345832 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&generation=3&command=filelist} 
status=0 QTime=0 
   [junit4]   2> 1345833 T7609 oash.SnapPuller.fetchLatestIndex Number of files 
in latest index in master: 19
   [junit4]   2> 1345834 T7609 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/index.20140918091447879
   [junit4]   2> 1345835 T7609 oasu.DefaultSolrCoreState.closeIndexWriter 
Closing IndexWriter...
   [junit4]   2> 1345835 T7609 oasu.DefaultSolrCoreState.closeIndexWriter 
Waiting until IndexWriter is unused... core=collection1
   [junit4]   2> 1345835 T7609 oasu.DefaultSolrCoreState.closeIndexWriter 
Rollback old IndexWriter... core=collection1
   [junit4]   2> 1345836 T7609 oash.SnapPuller.fetchLatestIndex Starting 
download to MockDirectoryWrapper(RAMDirectory(id=lucene-6503a467)) 
fullCopy=false
   [junit4]   2> 1345839 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_1.nvd&qt=/replication&wt=filestream&generation=3&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345843 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_1.fnm&qt=/replication&wt=filestream&generation=3&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345846 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_1.fdx&qt=/replication&wt=filestream&generation=3&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345849 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_1.doc&qt=/replication&wt=filestream&generation=3&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345850 T7609 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.tip because it already exists
   [junit4]   2> 1345850 T7609 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.si because it already exists
   [junit4]   2> 1345850 T7609 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.fdx because it already exists
   [junit4]   2> 1345853 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=segments_3&qt=/replication&wt=filestream&generation=3&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345854 T7609 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.fdt because it already exists
   [junit4]   2> 1345854 T7609 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.fnm because it already exists
   [junit4]   2> 1345857 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_1.nvm&qt=/replication&wt=filestream&generation=3&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345859 T7609 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.doc because it already exists
   [junit4]   2> 1345861 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_1.tip&qt=/replication&wt=filestream&generation=3&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345862 T7609 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.nvd because it already exists
   [junit4]   2> 1345862 T7609 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.tim because it already exists
   [junit4]   2> 1345865 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_1.si&qt=/replication&wt=filestream&generation=3&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345869 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_1.tim&qt=/replication&wt=filestream&generation=3&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345870 T7609 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.nvm because it already exists
   [junit4]   2> 1345873 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_1.fdt&qt=/replication&wt=filestream&generation=3&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345874 T7609 oash.SnapPuller.fetchLatestIndex Total time 
taken for download : 0 secs
   [junit4]   2> 1345885 T7609 oasu.DefaultSolrCoreState.openIndexWriter 
Creating new IndexWriter...
   [junit4]   2> 1345886 T7609 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=16, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 1345887 T7609 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=2
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-68c88421)),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-68c88421)),segFN=segments_3,generation=3}
   [junit4]   2> 1345887 T7609 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 3
   [junit4]   2> 1345887 T7609 oasu.DefaultSolrCoreState.openIndexWriter New 
IndexWriter is ready to be used.
   [junit4]   2> 1345889 T7609 oass.SolrIndexSearcher.<init> Opening 
Searcher@4c9754ee[collection1] main
   [junit4]   2> 1345889 T7602 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@4c9754ee[collection1] 
main{UninvertingDirectoryReader(Uninverting(_0(5.0.0):C1) 
Uninverting(_1(5.0.0):C1))}
   [junit4]   2> 1345889 T7609 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/index.20140918091447879
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/index.20140918091447879;done=true>>]
   [junit4]   2> 1345889 T7609 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/index.20140918091447879
   [junit4]   2> 1345890 T7594 C495 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wait=true&command=fetchindex&masterUrl=http://127.0.0.1:39022/solr/replication}
 status=0 QTime=65 
   [junit4]   2> 1345894 T7595 C495 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/select 
params={wt=javabin&version=2&sort=id+desc&q=name:empty2} hits=1 status=0 
QTime=1 
   [junit4]   2> 1345894 T7579 oash.TestReplicationHandler.rQuery Waited for 
0ms and found 1 docs
   [junit4]   2> 1345897 T7582 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&_trace=getDetails&command=details} 
status=0 QTime=0 
   [junit4]   2> 1345900 T7582 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&_trace=getDetails&command=details} 
status=0 QTime=0 
   [junit4]   2> 1345905 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details&slave=false} 
status=0 QTime=0 
   [junit4]   2> 1345906 T7595 C495 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&_trace=getDetails&command=details} 
status=0 QTime=3 
   [junit4]   2> 1345909 T7582 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&_trace=assertVersions&command=indexversion}
 status=0 QTime=0 
   [junit4]   2> 1345912 T7595 C495 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&_trace=assertVersions&command=indexversion}
 status=0 QTime=0 
   [junit4]   2> 1345915 T7582 C494 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[3]} 
0 0
   [junit4]   2> 1345918 T7582 C494 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1345920 T7582 C494 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=3
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-1bd1d85)),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-1bd1d85)),segFN=segments_3,generation=3}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-1bd1d85)),segFN=segments_4,generation=4}
   [junit4]   2> 1345921 T7582 C494 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 4
   [junit4]   2> 1345922 T7582 C494 oass.SolrIndexSearcher.<init> Opening 
Searcher@34d3305d[collection1] realtime
   [junit4]   2> 1345922 T7582 C494 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1345922 T7582 C494 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update 
params={openSearcher=false&wt=javabin&version=2&commit=true} {commit=} 0 4
   [junit4]   2> 1345925 T7611 oash.SnapPuller.<init> WARN 'masterUrl' must be 
specified without the /replication suffix
   [junit4]   2> 1345925 T7611 oash.SnapPuller.<init>  No value set for 
'pollInterval'. Timer Task not started.
   [junit4]   2> 1345927 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0 
   [junit4]   2> 1345928 T7611 oash.SnapPuller.fetchLatestIndex Master's 
generation: 4
   [junit4]   2> 1345928 T7611 oash.SnapPuller.fetchLatestIndex Slave's 
generation: 3
   [junit4]   2> 1345928 T7611 oash.SnapPuller.fetchLatestIndex Starting 
replication process
   [junit4]   2> 1345930 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&generation=4&command=filelist} 
status=0 QTime=0 
   [junit4]   2> 1345931 T7611 oash.SnapPuller.fetchLatestIndex Number of files 
in latest index in master: 28
   [junit4]   2> 1345932 T7611 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/index.20140918091447976
   [junit4]   2> 1345932 T7611 oasu.DefaultSolrCoreState.closeIndexWriter 
Closing IndexWriter...
   [junit4]   2> 1345933 T7611 oasu.DefaultSolrCoreState.closeIndexWriter 
Waiting until IndexWriter is unused... core=collection1
   [junit4]   2> 1345933 T7611 oasu.DefaultSolrCoreState.closeIndexWriter 
Rollback old IndexWriter... core=collection1
   [junit4]   2> 1345934 T7611 oash.SnapPuller.fetchLatestIndex Starting 
download to MockDirectoryWrapper(RAMDirectory(id=lucene-6ce5b4d3)) 
fullCopy=false
   [junit4]   2> 1345937 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_2.fdx&qt=/replication&wt=filestream&generation=4&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345938 T7611 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.si because it already exists
   [junit4]   2> 1345939 T7611 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.fdx because it already exists
   [junit4]   2> 1345939 T7611 oash.SnapPuller.downloadIndexFiles Skipping 
download for _1.nvm because it already exists
   [junit4]   2> 1345939 T7611 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.fnm because it already exists
   [junit4]   2> 1345939 T7611 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.fdt because it already exists
   [junit4]   2> 1345942 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_2.fnm&qt=/replication&wt=filestream&generation=4&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345945 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_2.nvd&qt=/replication&wt=filestream&generation=4&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345948 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_2.nvm&qt=/replication&wt=filestream&generation=4&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345949 T7611 oash.SnapPuller.downloadIndexFiles Skipping 
download for _1.tim because it already exists
   [junit4]   2> 1345949 T7611 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.nvm because it already exists
   [junit4]   2> 1345952 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_2.tip&qt=/replication&wt=filestream&generation=4&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345953 T7611 oash.SnapPuller.downloadIndexFiles Skipping 
download for _1.fdt because it already exists
   [junit4]   2> 1345953 T7611 oash.SnapPuller.downloadIndexFiles Skipping 
download for _1.fnm because it already exists
   [junit4]   2> 1345953 T7611 oash.SnapPuller.downloadIndexFiles Skipping 
download for _1.nvd because it already exists
   [junit4]   2> 1345956 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_2.tim&qt=/replication&wt=filestream&generation=4&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345957 T7611 oash.SnapPuller.downloadIndexFiles Skipping 
download for _1.fdx because it already exists
   [junit4]   2> 1345957 T7611 oash.SnapPuller.downloadIndexFiles Skipping 
download for _1.doc because it already exists
   [junit4]   2> 1345960 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_2.fdt&qt=/replication&wt=filestream&generation=4&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345961 T7611 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.tip because it already exists
   [junit4]   2> 1345964 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_2.doc&qt=/replication&wt=filestream&generation=4&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345965 T7611 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.doc because it already exists
   [junit4]   2> 1345965 T7611 oash.SnapPuller.downloadIndexFiles Skipping 
download for _1.tip because it already exists
   [junit4]   2> 1345968 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_2.si&qt=/replication&wt=filestream&generation=4&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345969 T7611 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.tim because it already exists
   [junit4]   2> 1345969 T7611 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.nvd because it already exists
   [junit4]   2> 1345969 T7611 oash.SnapPuller.downloadIndexFiles Skipping 
download for _1.si because it already exists
   [junit4]   2> 1345972 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=segments_4&qt=/replication&wt=filestream&generation=4&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1345973 T7611 oash.SnapPuller.fetchLatestIndex Total time 
taken for download : 0 secs
   [junit4]   2> 1345975 T7611 oasu.DefaultSolrCoreState.openIndexWriter 
Creating new IndexWriter...
   [junit4]   2> 1345976 T7611 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=16, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 1345977 T7611 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=3
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-68c88421)),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-68c88421)),segFN=segments_3,generation=3}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-68c88421)),segFN=segments_4,generation=4}
   [junit4]   2> 1345978 T7611 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 4
   [junit4]   2> 1345978 T7611 oasu.DefaultSolrCoreState.openIndexWriter New 
IndexWriter is ready to be used.
   [junit4]   2> 1345979 T7611 oass.SolrIndexSearcher.<init> Opening 
Searcher@769472c5[collection1] main
   [junit4]   2> 1345980 T7602 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@769472c5[collection1] 
main{UninvertingDirectoryReader(Uninverting(_0(5.0.0):C1) 
Uninverting(_1(5.0.0):C1) Uninverting(_2(5.0.0):C1))}
   [junit4]   2> 1345980 T7611 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/index.20140918091447976
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/index.20140918091447976;done=true>>]
   [junit4]   2> 1345980 T7611 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/index.20140918091447976
   [junit4]   2> 1345981 T7596 C495 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wait=true&command=fetchindex&masterUrl=http://127.0.0.1:39022/solr/replication}
 status=0 QTime=57 
   [junit4]   2> 1345985 T7582 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/select 
params={wt=javabin&version=2&sort=id+desc&q=name:empty3} hits=0 status=0 
QTime=1 
   [junit4]   2> 1345985 T7579 oash.TestReplicationHandler.rQuery Waited for 
0ms and found 0 docs
   [junit4]   2> 1345988 T7595 C495 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/select 
params={wt=javabin&version=2&sort=id+desc&q=name:empty3} hits=1 status=0 
QTime=0 
   [junit4]   2> 1345989 T7579 oash.TestReplicationHandler.rQuery Waited for 
0ms and found 1 docs
   [junit4]   2> 1345993 T7582 C494 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[4]} 
0 1
   [junit4]   2> 1345996 T7582 C494 oasu.DirectUpdateHandler2.commit start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1345998 T7582 C494 oasc.SolrDeletionPolicy.onCommit 
SolrDeletionPolicy.onCommit: commits: num=4
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-1bd1d85)),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-1bd1d85)),segFN=segments_3,generation=3}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-1bd1d85)),segFN=segments_4,generation=4}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-1bd1d85)),segFN=segments_5,generation=5}
   [junit4]   2> 1345999 T7582 C494 oasc.SolrDeletionPolicy.updateCommits 
newest commit generation = 5
   [junit4]   2> 1346000 T7582 C494 oass.SolrIndexSearcher.<init> Opening 
Searcher@265fa597[collection1] main
   [junit4]   2> 1346000 T7582 C494 oasu.DirectUpdateHandler2.commit 
end_commit_flush
   [junit4]   2> 1346000 T7590 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@265fa597[collection1] 
main{UninvertingDirectoryReader(Uninverting(_0(5.0.0):C1) 
Uninverting(_1(5.0.0):C1) Uninverting(_2(5.0.0):C1) Uninverting(_3(5.0.0):C1))}
   [junit4]   2> 1346001 T7582 C494 oasup.LogUpdateProcessor.finish 
[collection1] webapp=/solr path=/update 
params={wt=javabin&version=2&commit=true} {commit=} 0 5
   [junit4]   2> 1346003 T7613 oash.SnapPuller.<init> WARN 'masterUrl' must be 
specified without the /replication suffix
   [junit4]   2> 1346003 T7613 oash.SnapPuller.<init>  No value set for 
'pollInterval'. Timer Task not started.
   [junit4]   2> 1346005 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 
QTime=0 
   [junit4]   2> 1346006 T7613 oash.SnapPuller.fetchLatestIndex Master's 
generation: 5
   [junit4]   2> 1346006 T7613 oash.SnapPuller.fetchLatestIndex Slave's 
generation: 4
   [junit4]   2> 1346006 T7613 oash.SnapPuller.fetchLatestIndex Starting 
replication process
   [junit4]   2> 1346009 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&generation=5&command=filelist} 
status=0 QTime=1 
   [junit4]   2> 1346009 T7613 oash.SnapPuller.fetchLatestIndex Number of files 
in latest index in master: 37
   [junit4]   2> 1346010 T7613 oasc.CachingDirectoryFactory.get return new 
directory for 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/index.20140918091448055
   [junit4]   2> 1346011 T7613 oasu.DefaultSolrCoreState.closeIndexWriter 
Closing IndexWriter...
   [junit4]   2> 1346011 T7613 oasu.DefaultSolrCoreState.closeIndexWriter 
Waiting until IndexWriter is unused... core=collection1
   [junit4]   2> 1346011 T7613 oasu.DefaultSolrCoreState.closeIndexWriter 
Rollback old IndexWriter... core=collection1
   [junit4]   2> 1346012 T7613 oash.SnapPuller.fetchLatestIndex Starting 
download to MockDirectoryWrapper(RAMDirectory(id=lucene-66449dc0)) 
fullCopy=false
   [junit4]   2> 1346015 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_3.nvd&qt=/replication&wt=filestream&generation=5&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1346017 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _2.fdx because it already exists
   [junit4]   2> 1346017 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.si because it already exists
   [junit4]   2> 1346017 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.fdx because it already exists
   [junit4]   2> 1346017 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _1.nvm because it already exists
   [junit4]   2> 1346017 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.fnm because it already exists
   [junit4]   2> 1346018 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.fdt because it already exists
   [junit4]   2> 1346018 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _2.fnm because it already exists
   [junit4]   2> 1346018 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _2.nvd because it already exists
   [junit4]   2> 1346018 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _2.nvm because it already exists
   [junit4]   2> 1346021 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_3.doc&qt=/replication&wt=filestream&generation=5&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1346022 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _1.tim because it already exists
   [junit4]   2> 1346022 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.nvm because it already exists
   [junit4]   2> 1346022 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _2.tip because it already exists
   [junit4]   2> 1346025 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_3.nvm&qt=/replication&wt=filestream&generation=5&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1346026 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _1.fdt because it already exists
   [junit4]   2> 1346029 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=segments_5&qt=/replication&wt=filestream&generation=5&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1346030 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _1.fnm because it already exists
   [junit4]   2> 1346030 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _1.nvd because it already exists
   [junit4]   2> 1346033 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_3.fdt&qt=/replication&wt=filestream&generation=5&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1346034 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _2.tim because it already exists
   [junit4]   2> 1346034 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _1.fdx because it already exists
   [junit4]   2> 1346035 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _1.doc because it already exists
   [junit4]   2> 1346035 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _2.fdt because it already exists
   [junit4]   2> 1346035 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.tip because it already exists
   [junit4]   2> 1346038 T7583 C494 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_3.fnm&qt=/replication&wt=filestream&generation=5&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> ASYNC  NEW_CORE C496 name=collection1 
org.apache.solr.core.SolrCore@2b35a15c
   [junit4]   2> 1346060 T7583 C496 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_3.tim&qt=/replication&wt=filestream&generation=5&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1346061 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _2.doc because it already exists
   [junit4]   2> 1346062 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.doc because it already exists
   [junit4]   2> 1346062 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _1.tip because it already exists
   [junit4]   2> 1346065 T7583 C496 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_3.fdx&qt=/replication&wt=filestream&generation=5&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1346066 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _2.si because it already exists
   [junit4]   2> 1346066 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.tim because it already exists
   [junit4]   2> 1346066 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _0.nvd because it already exists
   [junit4]   2> 1346069 T7583 C496 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_3.si&qt=/replication&wt=filestream&generation=5&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1346070 T7613 oash.SnapPuller.downloadIndexFiles Skipping 
download for _1.si because it already exists
   [junit4]   2> 1346073 T7583 C496 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={file=_3.tip&qt=/replication&wt=filestream&generation=5&checksum=true&command=filecontent}
 status=0 QTime=0 
   [junit4]   2> 1346074 T7613 oash.SnapPuller.fetchLatestIndex Total time 
taken for download : 0 secs
   [junit4]   2> 1346076 T7613 oasu.DefaultSolrCoreState.openIndexWriter 
Creating new IndexWriter...
   [junit4]   2> 1346077 T7613 oasu.RandomMergePolicy.<init> RandomMergePolicy 
wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=16, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 1346079 T7613 oasc.SolrDeletionPolicy.onInit 
SolrDeletionPolicy.onInit: commits: num=4
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-68c88421)),segFN=segments_2,generation=2}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-68c88421)),segFN=segments_3,generation=3}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-68c88421)),segFN=segments_4,generation=4}
   [junit4]   2>                
commit{dir=MockDirectoryWrapper(RAMDirectory(id=lucene-68c88421)),segFN=segments_5,generation=5}
   [junit4]   2> 1346079 T7613 oasc.SolrDeletionPolicy.updateCommits newest 
commit generation = 5
   [junit4]   2> 1346079 T7613 oasu.DefaultSolrCoreState.openIndexWriter New 
IndexWriter is ready to be used.
   [junit4]   2> 1346081 T7613 oass.SolrIndexSearcher.<init> Opening 
Searcher@1e26cd72[collection1] main
   [junit4]   2> 1346082 T7602 oasc.SolrCore.registerSearcher [collection1] 
Registered new searcher Searcher@1e26cd72[collection1] 
main{UninvertingDirectoryReader(Uninverting(_0(5.0.0):C1) 
Uninverting(_1(5.0.0):C1) Uninverting(_2(5.0.0):C1) Uninverting(_3(5.0.0):C1))}
   [junit4]   2> 1346082 T7613 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/index.20140918091448055
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/index.20140918091448055;done=true>>]
   [junit4]   2> 1346082 T7613 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/index.20140918091448055
   [junit4]   2> ASYNC  NEW_CORE C497 name=collection1 
org.apache.solr.core.SolrCore@7ddd198d
   [junit4]   2> 1346082 T7596 C497 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={wait=true&command=fetchindex&masterUrl=http://127.0.0.1:39022/solr/replication}
 status=0 QTime=80 
   [junit4]   2> 1346087 T7582 C496 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/select 
params={wt=javabin&version=2&sort=id+desc&q=name:(empty1+empty2+empty3+empty4)} 
hits=4 status=0 QTime=1 
   [junit4]   2> 1346088 T7579 oash.TestReplicationHandler.rQuery Waited for 
0ms and found 4 docs
   [junit4]   2> 1346092 T7595 C497 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/select 
params={wt=javabin&version=2&sort=id+desc&q=name:(empty1+empty2+empty3+empty4)} 
hits=4 status=0 QTime=1 
   [junit4]   2> 1346093 T7579 oash.TestReplicationHandler.rQuery Waited for 
0ms and found 4 docs
   [junit4]   2> 1346095 T7582 C496 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&_trace=getDetails&command=details} 
status=0 QTime=0 
   [junit4]   2> 1346098 T7582 C496 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&_trace=getDetails&command=details} 
status=0 QTime=0 
   [junit4]   2> 1346104 T7583 C496 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&command=details&slave=false} 
status=0 QTime=0 
   [junit4]   2> 1346105 T7595 C497 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&_trace=getDetails&command=details} 
status=0 QTime=4 
   [junit4]   2> 1346108 T7582 C496 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&_trace=assertVersions&command=indexversion}
 status=0 QTime=0 
   [junit4]   2> 1346111 T7595 C497 oasc.SolrCore.execute [collection1] 
webapp=/solr path=/replication 
params={qt=/replication&wt=javabin&version=2&_trace=assertVersions&command=indexversion}
 status=0 QTime=0 
   [junit4]   2> 1346112 T7579 oas.SolrTestCaseJ4.tearDown ###Ending 
testEmptyCommits
   [junit4]   2> 1346112 T7579 oasc.CoreContainer.shutdown Shutting down 
CoreContainer instance=306970080
   [junit4]   2> 1346113 T7579 oasc.SolrCore.close [collection1]  CLOSING 
SolrCore org.apache.solr.core.SolrCore@2b35a15c
   [junit4]   2> 1346114 T7579 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=4,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
   [junit4]   2> 1346115 T7579 oasu.SolrCoreState.decrefSolrCoreState Closing 
SolrCoreState
   [junit4]   2> 1346115 T7579 oasu.DefaultSolrCoreState.closeIndexWriter 
SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1346115 T7579 oasu.DefaultSolrCoreState.closeIndexWriter 
closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1346117 T7579 oasc.SolrCore.closeSearcher [collection1] 
Closing main searcher on request.
   [junit4]   2> 1346118 T7579 oasc.CachingDirectoryFactory.close Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1346118 T7579 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/data
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/data;done=false>>]
   [junit4]   2> 1346118 T7579 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/data
   [junit4]   2> 1346119 T7579 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/data/index
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/data/index;done=false>>]
   [junit4]   2> 1346119 T7579 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/data/index
   [junit4]   2> 1346120 T7579 oejsh.ContextHandler.doStop stopped 
o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 1351115 T7579 oasc.CoreContainer.shutdown Shutting down 
CoreContainer instance=438540849
   [junit4]   2> 1351116 T7579 oasc.SolrCore.close [collection1]  CLOSING 
SolrCore org.apache.solr.core.SolrCore@7ddd198d
   [junit4]   2> 1361958 T7603 oash.SnapPuller.fetchLatestIndex ERROR Master 
at: http://127.0.0.1:39022/solr is not available. Index fetch failed. 
Exception: IOException occured when talking to server at: 
http://127.0.0.1:39022/solr
   [junit4]   2> 1361959 T7579 oasu.DirectUpdateHandler2.close closing 
DirectUpdateHandler2{commits=0,autocommits=0,soft 
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
   [junit4]   2> 1361960 T7579 oasu.SolrCoreState.decrefSolrCoreState Closing 
SolrCoreState
   [junit4]   2> 1361960 T7579 oasu.DefaultSolrCoreState.closeIndexWriter 
SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1361960 T7579 oasu.DefaultSolrCoreState.closeIndexWriter 
closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1361962 T7579 oasc.SolrCore.closeSearcher [collection1] 
Closing main searcher on request.
   [junit4]   2> 1361963 T7579 oasc.CachingDirectoryFactory.close Closing 
MockDirectoryFactory - 2 di

[...truncated too long message...]

 T7579 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 
directories currently being tracked
   [junit4]   2> 2457759 T7579 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/data
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/data;done=false>>]
   [junit4]   2> 2457760 T7579 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/data
   [junit4]   2> 2457760 T7579 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/data/index
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/data/index;done=false>>]
   [junit4]   2> 2457760 T7579 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-001/collection1/data/index
   [junit4]   2> 2457761 T7579 oejsh.ContextHandler.doStop stopped 
o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 2457956 T10542 oash.SnapPuller$1.run Poll disabled
   [junit4]   2> 2458956 T10542 oash.SnapPuller$1.run Poll disabled
   [junit4]   2> 2459955 T10542 oash.SnapPuller$1.run Poll disabled
   [junit4]   2> 2460956 T10542 oash.SnapPuller$1.run Poll disabled
   [junit4]   2> 2461956 T10542 oash.SnapPuller$1.run Poll disabled
   [junit4]   2> 2462758 T7579 oasc.CoreContainer.shutdown Shutting down 
CoreContainer instance=880791593
   [junit4]   2> 2462759 T7579 oasc.SolrCore.close [collection1]  CLOSING 
SolrCore org.apache.solr.core.SolrCore@29e49fd3
   [junit4]   2> 2462760 T7579 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=5,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
   [junit4]   2> 2462761 T7579 oasu.SolrCoreState.decrefSolrCoreState Closing 
SolrCoreState
   [junit4]   2> 2462761 T7579 oasu.DefaultSolrCoreState.closeIndexWriter 
SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 2462762 T7579 oasu.DefaultSolrCoreState.closeIndexWriter 
closing IndexWriter with IndexWriterCloser
   [junit4]   2> 2462763 T7579 oasc.SolrCore.closeSearcher [collection1] 
Closing main searcher on request.
   [junit4]   2> 2462764 T7579 oasc.CachingDirectoryFactory.close Closing 
MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 2462764 T7579 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/index
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/index;done=false>>]
   [junit4]   2> 2462765 T7579 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data/index
   [junit4]   2> 2462765 T7579 oasc.CachingDirectoryFactory.closeCacheValue 
looking to close 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data
 
[CachedDir<<refCount=0;path=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data;done=false>>]
   [junit4]   2> 2462765 T7579 oasc.CachingDirectoryFactory.close Closing 
directory: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001/solr-instance-002/collection1/data
   [junit4]   2> 2462766 T7579 oejsh.ContextHandler.doStop stopped 
o.e.j.s.ServletContextHandler{/solr,null}
   [junit4]   2> 2467768 T7579 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J3/temp/solr.handler.TestReplicationHandler-47FD3A04C59217E5-001
   [junit4]   2> 1122671 T7578 ccr.ThreadLeakControl.checkThreadLeaks WARNING 
Will linger awaiting termination of 1 leaked thread(s).
   [junit4]   2> 2472757 T10549 oash.SnapPuller.fetchLatestIndex ERROR Master 
at: http://127.0.0.1:56452/solr is not available. Index fetch failed. 
Exception: IOException occured when talking to server at: 
http://127.0.0.1:56452/solr
   [junit4]   2> 2472758 T10549 oasc.SolrException.log ERROR SnapPull failed 
:org.apache.lucene.store.AlreadyClosedException: Already closed
   [junit4]   2>                at 
org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:340)
   [junit4]   2>                at 
org.apache.solr.handler.ReplicationHandler.loadReplicationProperties(ReplicationHandler.java:821)
   [junit4]   2>                at 
org.apache.solr.handler.SnapPuller.logReplicationTimeAndConfFiles(SnapPuller.java:570)
   [junit4]   2>                at 
org.apache.solr.handler.SnapPuller.fetchLatestIndex(SnapPuller.java:512)
   [junit4]   2>                at 
org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:336)
   [junit4]   2>                at 
org.apache.solr.handler.ReplicationHandler$1.run(ReplicationHandler.java:227)
   [junit4]   2>        
   [junit4]   2> NOTE: test params are: codec=CheapBastard, 
sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=nl, 
timezone=Europe/Berlin
   [junit4]   2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_65 
(64-bit)/cpus=16,threads=1,free=171638664,total=332922880
   [junit4]   2> NOTE: All tests run in this JVM: [TestSolr4Spatial2, 
SolrPluginUtilsTest, TestDocSet, SpellCheckComponentTest, 
SolrIndexSplitterTest, TestRecoveryHdfs, ShardRoutingTest, 
TestExceedMaxTermLength, SharedFSAutoReplicaFailoverUtilsTest, 
SolrTestCaseJ4Test, AddSchemaFieldsUpdateProcessorFactoryTest, 
TestBlendedInfixSuggestions, FileBasedSpellCheckerTest, 
CollectionsAPIAsyncDistributedZkTest, TestHighFrequencyDictionaryFactory, 
FieldMutatingUpdateProcessorTest, TestDocBasedVersionConstraints, 
SearchHandlerTest, StandardRequestHandlerTest, TestCoreDiscovery, 
TestNonNRTOpen, TestDistribDocBasedVersion, TestFastWriter, TestLRUCache, 
LeaderElectionTest, LoggingHandlerTest, TestBadConfig, SOLR749Test, 
EchoParamsTest, TestFieldResource, DocExpirationUpdateProcessorFactoryTest, 
BlockCacheTest, FieldAnalysisRequestHandlerTest, TestFieldCollectionResource, 
TestJoin, SuggestComponentTest, TermVectorComponentDistributedTest, 
SharedFSAutoReplicaFailoverTest, TestCloudInspectUtil, TestFastLRUCache, 
TestCursorMarkWithoutUniqueKey, TermVectorComponentTest, 
TestSortingResponseWriter, TestMergePolicyConfig, ResponseLogComponentTest, 
TestSearchPerf, OutputWriterTest, TestCustomSort, TestPhraseSuggestions, 
DeleteLastCustomShardedReplicaTest, 
TestOverriddenPrefixQueryForCustomFieldType, BasicDistributedZkTest, 
BasicDistributedZk2Test, SyncSliceTest, TestReplicationHandler]
   [junit4] Completed on J3 in 1127.74s, 13 tests, 1 failure <<< FAILURES!

[...truncated 166 lines...]
BUILD FAILED
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:498:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:471:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:60:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/extra-targets.xml:39:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:189:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:496:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1358:
 The following error occurred while executing this line:
/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:961:
 There were test failures: 429 suites, 1772 tests, 1 failure, 55 ignored (11 
assumptions)

Total time: 190 minutes 22 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Sending artifact delta relative to Lucene-Solr-NightlyTests-trunk #629
Archived 5 artifacts
Archive block size is 32768
Received 0 blocks and 698091178 bytes
Compression is 0.0%
Took 1 min 58 sec
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org