[
https://issues.apache.org/jira/browse/LUCENE-4676?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13552836#comment-13552836
]
Simon Willnauer commented on LUCENE-4676:
-----------------------------------------
to visualize this again here is a commented Log from a failure:
{panel}
....
IW [Thread-623]: getReader took 2 msec
CMS [Lucene Merge Thread #0]: merge thread: start
TEST [Thread-623]: refresh after delete {color:red}<<<<========== HERE WE
REFRESH AFTER THE DEL BY QUERY{color}
DW [Thread-623]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false
pendingChangesInFullFlush: false
IW [Thread-623]: nrtIsCurrent: infoVersion matches: true DW changes: true BD
changes: true
DW [Thread-623]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false
pendingChangesInFullFlush: false
DW [Thread-623]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false
pendingChangesInFullFlush: false
DW [Thread-623]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false
pendingChangesInFullFlush: false
DW [Thread-623]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false
pendingChangesInFullFlush: false
IW [Thread-623]: nrtIsCurrent: infoVersion matches: true DW changes: true BD
changes: true
DW [Thread-623]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false
pendingChangesInFullFlush: false
DW [Thread-623]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false
pendingChangesInFullFlush: false
DW [Thread-623]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false
pendingChangesInFullFlush: false
IW [Thread-623]: flush at getReader
DW [Thread-623]: Thread-623 startFullFlush
DW [Thread-623]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false
pendingChangesInFullFlush: false
DWFC [Thread-623]: addFlushableState DocumentsWriterPerThread
[pendingDeletes=gen=0, segment=null, aborting=false, numDocsInRAM=0,
deleteQueue=DWDQ: [ generation: 3 ]]
DW [Thread-623]: Thread-623: flush naked frozen global deletes
{color:red}<<<<========== HERE WE PUSH THE DEL BY QUERY TO THE BUFFERED DELETE
STREAM{color}
BD [Thread-623]: push deletes 1 deleted queries bytesUsed=32 delGen=4
packetCount=2 totBytesUsed=1056
DW [Thread-623]: flush: push buffered deletes: 1 deleted queries bytesUsed=32
BD [Lucene Merge Thread #0]: applyDeletes: infos=[_1(5.0):c1, _0(5.0):C1]
packetCount=2
BD [Lucene Merge Thread #0]: seg=_1(5.0):c1 segGen=3 coalesced
deletes=[CoalescedDeletes(termSets=1,queries=1)] newDelCount=0
BD [Lucene Merge Thread #0]: seg=_0(5.0):C1 segGen=1 coalesced
deletes=[CoalescedDeletes(termSets=2,queries=1)] newDelCount=0
BD [Lucene Merge Thread #0]: applyDeletes took 0 msec
{color:red}<<<<========== THE MERGE KICKS IN{color}
BD [Lucene Merge Thread #0]: prune
sis=org.apache.lucene.index.SegmentInfos@6dfb8d2e minGen=5 packetCount=2
BD [Lucene Merge Thread #0]: pruneDeletes: prune 2 packets; 0 packets remain
{color:red}<<<<========== MERGE PRUNES AWAY THE PACKAGE{color}
IW [Lucene Merge Thread #0]: merge seg=_2 _1(5.0):c1 _0(5.0):C1
IW [Lucene Merge Thread #0]: now merge
merge=_1(5.0):c1 _0(5.0):C1
index=_0(5.0):C1 _1(5.0):c1
IW [Lucene Merge Thread #0]: merging _1(5.0):c1 _0(5.0):C1
IW [Thread-623]: don't apply deletes now delTermCount=0 bytesUsed=0
IW [Thread-623]: return reader version=6 reader=StandardDirectoryReader(:nrt
_0(5.0):C1 _1(5.0):c1)
DW [Thread-623]: Thread-623 finishFullFlush success=true
IW [Thread-623]: getReader took 1 msec {color:red}<<<<========== HERE WE ARE
DONE REFRESHING AFTER THE DELETE -- DEL QUERY IS ALREADY GONE {color}
IW [Lucene Merge Thread #0]: seg=_1(5.0):c1 no deletes
IW [Lucene Merge Thread #0]: seg=_0(5.0):C1 no deletes
TEST
[TEST-TestNRTManager.testThreadStarvationNoDeleteNRTReader-seed#[925ECD106FBFA3FF]]:
done updating
DW
[TEST-TestNRTManager.testThreadStarvationNoDeleteNRTReader-seed#[925ECD106FBFA3FF]]:
anyChanges? numDocsInRam=0 deletes=false hasTickets:false
pendingChangesInFullFlush: false
IW
[TEST-TestNRTManager.testThreadStarvationNoDeleteNRTReader-seed#[925ECD106FBFA3FF]]:
nrtIsCurrent: infoVersion matches: true DW changes: false BD changes: false
{color:red}<<<<========== HERE WE ARE ASSERTING ON isCurrent == FALSE and
FAIL!!{color}
DW
[TEST-TestNRTManager.testThreadStarvationNoDeleteNRTReader-seed#[925ECD106FBFA3FF]]:
anyChanges? numDocsInRam=0 deletes=false hasTickets:false
pendingChangesInFullFlush: false
DW
[TEST-TestNRTManager.testThreadStarvationNoDeleteNRTReader-seed#[925ECD106FBFA3FF]]:
anyChanges? numDocsInRam=0 deletes=false hasTickets:false
pendingChangesInFullFlush: false
SM [Lucene Merge Thread #0]: merge store matchedCount=2 vs 2
{panel}
> IndexReader.isCurrent race
> --------------------------
>
> Key: LUCENE-4676
> URL: https://issues.apache.org/jira/browse/LUCENE-4676
> Project: Lucene - Core
> Issue Type: Bug
> Reporter: Robert Muir
> Assignee: Simon Willnauer
> Fix For: 4.1
>
>
> Revision: 1431169
> ant test -Dtestcase=TestNRTManager
> -Dtests.method=testThreadStarvationNoDeleteNRTReader
> -Dtests.seed=925ECD106FBFA3FF -Dtests.slow=true -Dtests.locale=fr_CA
> -Dtests.timezone=America/Kentucky/Louisville -Dtests.file.encoding=US-ASCII
> -Dtests.dups=500
--
This message is automatically generated by JIRA.
If you think it was sent incorrectly, please contact your JIRA administrators
For more information on JIRA, see: http://www.atlassian.com/software/jira
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org
