Skip to content

Instantly share code, notes, and snippets.

@nneul

nneul/gist:1006d0b1111d5175f3cb

Created June 12, 2014 23:52
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save nneul/1006d0b1111d5175f3cb to your computer and use it in GitHub Desktop.
Save nneul/1006d0b1111d5175f3cb to your computer and use it in GitHub Desktop.
Download ZIP
Raw
gistfile1.txt
2014-06-12 21:34:23,985 INFO qtp1776334754-54122 [solr.update.processor.LogUpdateProcessor] - [d-_v22_shard1_replica2] webapp=/solr path=/update params={distrib.from=http://10.220.16.204:8983/solr/d-_v22_shard1_replica1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[8d44b6e6-ab5f-4ce9-9270-82c74f3ed88d (1470741991390183424)]} 0 768
2014-06-12 21:34:24,178 INFO qtp1776334754-54122 [org.apache.solr.update.UpdateHandler ] - start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
2014-06-12 21:34:24,179 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: commit: start
2014-06-12 21:34:24,179 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: commit: enter lock
2014-06-12 21:34:24,179 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: commit: now prepare
2014-06-12 21:34:24,179 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: prepareCommit: flush
2014-06-12 21:34:24,179 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: index before flush _1be8(4.8):C3482/114:delGen=114 _1i99(4.8):C6015/1967:delGen=1752 _1kxp(4.8):C3344/98:delGen=98 _1lyh(4.8):C912/1:delGen=1 _1m34(4.8):C95/1:delGen=1 _1m35(4.8):C1 _1m37(4.8):C1 _1m39(4.8):C1 _1m3b(4.8):C1 _1m3c(4.8):C1
2014-06-12 21:34:24,179 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DW][qtp1776334754-54122]: startFullFlush
2014-06-12 21:34:24,180 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DW][qtp1776334754-54122]: anyChanges? numDocsInRam=1 deletes=true hasTickets:false pendingChangesInFullFlush: false
2014-06-12 21:34:24,180 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DWFC][qtp1776334754-54122]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0, segment=_1m3d, aborting=false, numDocsInRAM=1, deleteQueue=DWDQ: [ generation: 122035 ]]
2014-06-12 21:34:24,180 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DWPT][qtp1776334754-54122]: flush postings as segment _1m3d numDocs=1
2014-06-12 21:34:24,242 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DWPT][qtp1776334754-54122]: new segment has 0 deleted docs
2014-06-12 21:34:24,243 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DWPT][qtp1776334754-54122]: new segment has no vectors; norms; no docValues; prox; freqs
2014-06-12 21:34:24,243 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DWPT][qtp1776334754-54122]: flushedFiles=[_1m3d_Lucene41_0.tip, _1m3d.fdt, _1m3d_Lucene41_0.pos, _1m3d.nvd, _1m3d_Lucene41_0.doc, _1m3d.fdx, _1m3d.fnm, _1m3d_Lucene41_0.tim, _1m3d.nvm]
2014-06-12 21:34:24,243 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DWPT][qtp1776334754-54122]: flushed codec=Lucene46
2014-06-12 21:34:24,243 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DWPT][qtp1776334754-54122]: flushed: segment=_1m3d ramUsed=1.096 MB newFlushedSize(includes docstores)=0.66 MB docs/MB=1.515
2014-06-12 21:34:24,243 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DW][qtp1776334754-54122]: publishFlushedSegment seg-private updates=null
2014-06-12 21:34:24,244 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: publishFlushedSegment
2014-06-12 21:34:24,244 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: push deletes 1 deleted terms (unique count=1) bytesUsed=1024 delGen=252431 packetCount=1 totBytesUsed=1024
2014-06-12 21:34:24,244 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: publish sets newSegment delGen=252432 seg=_1m3d(4.8):C1
2014-06-12 21:34:24,244 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IFD][qtp1776334754-54122]: now checkpoint "_1be8(4.8):C3482/114:delGen=114 _1i99(4.8):C6015/1967:delGen=1752 _1kxp(4.8):C3344/98:delGen=98 _1lyh(4.8):C912/1:delGen=1 _1m34(4.8):C95/1:delGen=1 _1m35(4.8):C1 _1m37(4.8):C1 _1m39(4.8):C1 _1m3b(4.8):C1 _1m3c(4.8):C1 _1m3d(4.8):C1" [11 segments ; isCommit = false]
2014-06-12 21:34:24,244 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IFD][qtp1776334754-54122]: 0 msec to checkpoint
2014-06-12 21:34:24,244 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: apply all deletes during flush
2014-06-12 21:34:24,244 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: applyDeletes: infos=[_1be8(4.8):C3482/114:delGen=114, _1i99(4.8):C6015/1967:delGen=1752, _1kxp(4.8):C3344/98:delGen=98, _1lyh(4.8):C912/1:delGen=1, _1m34(4.8):C95/1:delGen=1, _1m35(4.8):C1, _1m37(4.8):C1, _1m39(4.8):C1, _1m3b(4.8):C1, _1m3c(4.8):C1, _1m3d(4.8):C1] packetCount=1
2014-06-12 21:34:24,245 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: seg=_1m3c(4.8):C1 segGen=252429 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:24,245 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: seg=_1m3b(4.8):C1 segGen=252429 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:24,245 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: seg=_1m39(4.8):C1 segGen=252429 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:24,246 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: seg=_1m37(4.8):C1 segGen=252429 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:24,246 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: seg=_1m35(4.8):C1 segGen=252429 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:24,246 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: seg=_1m34(4.8):C95/1:delGen=1 segGen=252429 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:24,246 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: seg=_1lyh(4.8):C912/1:delGen=1 segGen=252429 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:24,246 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: seg=_1kxp(4.8):C3344/98:delGen=98 segGen=252429 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:24,247 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: seg=_1i99(4.8):C6015/1967:delGen=1752 segGen=252429 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:24,247 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: seg=_1be8(4.8):C3482/114:delGen=114 segGen=252429 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:24,247 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: applyDeletes took 3 msec
2014-06-12 21:34:24,247 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: prune sis=org.apache.lucene.index.SegmentInfos@3d0cd13f minGen=252433 packetCount=1
2014-06-12 21:34:24,247 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: pruneDeletes: prune 1 packets; 0 packets remain
2014-06-12 21:34:24,248 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DW][qtp1776334754-54122]: qtp1776334754-54122 finishFullFlush success=true
2014-06-12 21:34:24,248 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: findMerges: 11 segments
2014-06-12 21:34:24,249 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: seg=_1be8(4.8):C3482/114:delGen=114 size=33.378 MB
2014-06-12 21:34:24,249 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: seg=_1kxp(4.8):C3344/98:delGen=98 size=25.316 MB
2014-06-12 21:34:24,274 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: seg=_1i99(4.8):C6015/1967:delGen=1752 size=23.059 MB
2014-06-12 21:34:24,274 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: seg=_1lyh(4.8):C912/1:delGen=1 size=5.530 MB
2014-06-12 21:34:24,274 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: seg=_1m34(4.8):C95/1:delGen=1 size=1.201 MB [floored]
2014-06-12 21:34:24,274 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: seg=_1m3d(4.8):C1 size=0.660 MB [floored]
2014-06-12 21:34:24,274 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: seg=_1m35(4.8):C1 size=0.088 MB [floored]
2014-06-12 21:34:24,275 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: seg=_1m3c(4.8):C1 size=0.065 MB [floored]
2014-06-12 21:34:24,275 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: seg=_1m3b(4.8):C1 size=0.025 MB [floored]
2014-06-12 21:34:24,275 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: seg=_1m39(4.8):C1 size=0.015 MB [floored]
2014-06-12 21:34:24,275 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: seg=_1m37(4.8):C1 size=0.014 MB [floored]
2014-06-12 21:34:24,276 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: allowedSegmentCount=14 vs count=11 (eligible count=11) tooBigCount=0
2014-06-12 21:34:24,276 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [CMS][qtp1776334754-54122]: now merge
2014-06-12 21:34:24,276 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [CMS][qtp1776334754-54122]: index: _1be8(4.8):C3482/114:delGen=114 _1i99(4.8):C6015/1967:delGen=1752 _1kxp(4.8):C3344/98:delGen=98 _1lyh(4.8):C912/1:delGen=1 _1m34(4.8):C95/1:delGen=1 _1m35(4.8):C1 _1m37(4.8):C1 _1m39(4.8):C1 _1m3b(4.8):C1 _1m3c(4.8):C1 _1m3d(4.8):C1
2014-06-12 21:34:24,276 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [CMS][qtp1776334754-54122]: no more merges pending; now return
2014-06-12 21:34:24,276 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: startCommit(): start
2014-06-12 21:34:24,276 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: startCommit index=_1be8(4.8):C3482/114:delGen=114 _1i99(4.8):C6015/1967:delGen=1752 _1kxp(4.8):C3344/98:delGen=98 _1lyh(4.8):C912/1:delGen=1 _1m34(4.8):C95/1:delGen=1 _1m35(4.8):C1 _1m37(4.8):C1 _1m39(4.8):C1 _1m3b(4.8):C1 _1m3c(4.8):C1 _1m3d(4.8):C1 changeCount=226638
2014-06-12 21:34:24,331 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: done all syncs: [_1m35_Lucene41_0.doc, _1kxp.nvm, _1m3d_Lucene41_0.pos, _1m37_Lucene41_0.pos, _1m37_Lucene41_0.tip, _1lyh_1.del, _1m37_Lucene41_0.tim, _1m35.nvd, _1be8.fdx, _1m35.nvm, _1m37.fnm, _1m3c_Lucene41_0.doc, _1kxp.fdx, _1be8.fdt, _1m37.fdx, _1m3b_Lucene41_0.doc, _1kxp.fdt, _1m37.fdt, _1m35_Lucene41_0.pos, _1m35_Lucene41_0.tip, _1m39.fnm, _1m35_Lucene41_0.tim, _1be8.si, _1m3d.nvd, _1kxp.si, _1m34.fnm, _1m3b_Lucene41_0.pos, _1m3c_Lucene41_0.pos, _1kxp.nvd, _1m3b.fdt, _1m39_Lucene41_0.doc, _1m3b.fdx, _1kxp_Lucene41_0.pos, _1kxp.fnm, _1m39.fdx, _1lyh.fnm, _1m3d.fdt, _1m3c.nvd, _1i99_Lucene41_0.pos, _1m39.fdt, _1be8_36.del, _1m3d.fdx, _1m35.si, _1m3c.fnm, _1m3b.nvd, _1kxp_Lucene41_0.doc, _1i99.fdt, _1m34.fdx, _1kxp_2q.del, _1lyh_Lucene41_0.tip, _1m39.nvd, _1i99.fdx, _1lyh_Lucene41_0.tim, _1lyh.fdt, _1lyh.fdx, _1m34.fdt, _1m39.nvm, _1m3c.nvm, _1m34_Lucene41_0.tim, _1i99_Lucene41_0.doc, _1lyh.nvd, _1m3c_Lucene41_0.tim, _1m3c_Lucene41_0.tip, _1i99_1co.del, _1be8_Lucene41_0.doc, _1be8.nvd, _1m35.fdx, _1m3b.nvm, _1lyh_Lucene41_0.doc, _1m3c.fdt, _1m3d_Lucene41_0.doc, _1be8.nvm, _1m35.fdt, _1m37.nvm, _1m3d.fnm, _1m3c.fdx, _1m37_Lucene41_0.doc, _1m3b_Lucene41_0.tim, _1m37.nvd, _1i99.fnm, _1m3b_Lucene41_0.tip, _1m3d.si, _1m34_1.del, _1m34_Lucene41_0.tip, _1i99.nvm, _1m39_Lucene41_0.tip, _1m34_Lucene41_0.pos, _1m34.si, _1m39_Lucene41_0.tim, _1m34.nvd, _1i99.nvd, _1m35.fnm, _1be8_Lucene41_0.tip, _1m34.nvm, _1m37.si, _1m39_Lucene41_0.pos, _1i99.si, _1m3b.si, _1m3c.si, _1m3d.nvm, _1be8_Lucene41_0.tim, _1m3d_Lucene41_0.tip, _1m34_Lucene41_0.doc, _1be8_Lucene41_0.pos, _1i99_Lucene41_0.tip, _1i99_Lucene41_0.tim, _1m3b.fnm, _1kxp_Lucene41_0.tim, _1be8.fnm, _1m39.si, _1kxp_Lucene41_0.tip, _1lyh.si, _1lyh.nvm, _1m3d_Lucene41_0.tim, _1lyh_Lucene41_0.pos]
2014-06-12 21:34:24,331 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: commit: pendingCommit != null
2014-06-12 21:34:24,339 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: commit: wrote segments file "segments_1dko"
2014-06-12 21:34:24,339 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IFD][qtp1776334754-54122]: now checkpoint "_1be8(4.8):C3482/114:delGen=114 _1i99(4.8):C6015/1967:delGen=1752 _1kxp(4.8):C3344/98:delGen=98 _1lyh(4.8):C912/1:delGen=1 _1m34(4.8):C95/1:delGen=1 _1m35(4.8):C1 _1m37(4.8):C1 _1m39(4.8):C1 _1m3b(4.8):C1 _1m3c(4.8):C1 _1m3d(4.8):C1" [11 segments ; isCommit = true]
2014-06-12 21:34:24,340 INFO qtp1776334754-54122 [org.apache.solr.core.SolrCore ] - SolrDeletionPolicy.onCommit: commits: num=2
commit{dir=NRTCachingDirectory(MMapDirectory@/local/solr/solr-4.8.0/example/solr/d-_v22_shard1_replica2/data/index lockFactory=NativeFSLockFactory@/local/solr/solr-4.8.0/example/solr/d-_v22_shard1_replica2/data/index; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1dkn,generation=64247}
commit{dir=NRTCachingDirectory(MMapDirectory@/local/solr/solr-4.8.0/example/solr/d-_v22_shard1_replica2/data/index lockFactory=NativeFSLockFactory@/local/solr/solr-4.8.0/example/solr/d-_v22_shard1_replica2/data/index; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1dko,generation=64248}
2014-06-12 21:34:24,340 INFO qtp1776334754-54122 [org.apache.solr.core.SolrCore ] - newest commit generation = 64248
2014-06-12 21:34:24,340 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IFD][qtp1776334754-54122]: deleteCommits: now decRef commit "segments_1dkn"
2014-06-12 21:34:24,340 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IFD][qtp1776334754-54122]: delete "segments_1dkn"
2014-06-12 21:34:24,340 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IFD][qtp1776334754-54122]: 1 msec to checkpoint
2014-06-12 21:34:24,341 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: commit: done
2014-06-12 21:34:24,341 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DW][qtp1776334754-54122]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
2014-06-12 21:34:24,341 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: nrtIsCurrent: infoVersion matches: false; DW changes: false; BD changes: false
2014-06-12 21:34:24,342 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: flush at getReader
2014-06-12 21:34:24,342 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DW][qtp1776334754-54122]: startFullFlush
2014-06-12 21:34:24,342 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DW][qtp1776334754-54122]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
2014-06-12 21:34:24,342 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: apply all deletes during flush
2014-06-12 21:34:24,342 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: applyDeletes: no deletes; skipping
2014-06-12 21:34:24,342 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: prune sis=org.apache.lucene.index.SegmentInfos@3d0cd13f minGen=252433 packetCount=0
2014-06-12 21:34:24,344 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: return reader version=167547 reader=StandardDirectoryReader(segments_1dko:167547:nrt _1be8(4.8):C3482/114:delGen=114 _1i99(4.8):C6015/1967:delGen=1752 _1kxp(4.8):C3344/98:delGen=98 _1lyh(4.8):C912/1:delGen=1 _1m34(4.8):C95/1:delGen=1 _1m35(4.8):C1 _1m37(4.8):C1 _1m39(4.8):C1 _1m3b(4.8):C1 _1m3c(4.8):C1 _1m3d(4.8):C1)
2014-06-12 21:34:24,345 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DW][qtp1776334754-54122]: qtp1776334754-54122 finishFullFlush success=true
2014-06-12 21:34:24,345 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: getReader took 3 msec
2014-06-12 21:34:24,345 INFO qtp1776334754-54122 [org.apache.solr.search.SolrIndexSearcher] - Opening Searcher@79dc84d3[d-_v22_shard1_replica2] main
2014-06-12 21:34:24,346 INFO searcherExecutor-32-thread-1 [org.apache.solr.core.SolrCore ] - QuerySenderListener sending requests to Searcher@79dc84d3[d-_v22_shard1_replica2] main{StandardDirectoryReader(segments_1dko:167547:nrt _1be8(4.8):C3482/114:delGen=114 _1i99(4.8):C6015/1967:delGen=1752 _1kxp(4.8):C3344/98:delGen=98 _1lyh(4.8):C912/1:delGen=1 _1m34(4.8):C95/1:delGen=1 _1m35(4.8):C1 _1m37(4.8):C1 _1m39(4.8):C1 _1m3b(4.8):C1 _1m3c(4.8):C1 _1m3d(4.8):C1)}
2014-06-12 21:34:24,346 INFO searcherExecutor-32-thread-1 [org.apache.solr.core.SolrCore ] - QuerySenderListener done.
2014-06-12 21:34:24,346 INFO searcherExecutor-32-thread-1 [org.apache.solr.core.SolrCore ] - [d-_v22_shard1_replica2] Registered new searcher Searcher@79dc84d3[d-_v22_shard1_replica2] main{StandardDirectoryReader(segments_1dko:167547:nrt _1be8(4.8):C3482/114:delGen=114 _1i99(4.8):C6015/1967:delGen=1752 _1kxp(4.8):C3344/98:delGen=98 _1lyh(4.8):C912/1:delGen=1 _1m34(4.8):C95/1:delGen=1 _1m35(4.8):C1 _1m37(4.8):C1 _1m39(4.8):C1 _1m3b(4.8):C1 _1m3c(4.8):C1 _1m3d(4.8):C1)}
2014-06-12 21:34:24,347 INFO qtp1776334754-54122 [org.apache.solr.update.UpdateHandler ] - end_commit_flush
2014-06-12 21:34:24,347 INFO qtp1776334754-54122 [solr.update.processor.LogUpdateProcessor] - [d-_v22_shard1_replica2] webapp=/solr path=/update params={waitSearcher=true&distrib.from=http://10.220.16.204:8983/solr/d-_v22_shard1_replica1/&update.distrib=FROMLEADER&openSearcher=true&commit=true&wt=javabin&expungeDeletes=false&commit_end_point=true&version=2&softCommit=false} {commit=} 0 169
2014-06-12 21:34:26,148 INFO qtp1776334754-54437 [org.apache.solr.core.SolrCore ] - [d-_v22_shard1_replica2] webapp=/solr path=/select params={fl=id,hive,timestamp,type,message_id,file_instance_id,score&sort=at+desc,+id+desc&indent=on&start=0&q=%2B(*)&wt=json&fq=%2Bhive:kduffie1+AND+%2Bchannel:(adam+aws+channel1+channel2+deploy+dev+no-reply+no-reply-aws+notifications+share+test+trouble)+AND+at:[2014-06-12T21:32:26Z+TO+*]&rows=300} hits=0 status=0 QTime=2
2014-06-12 21:34:26,163 INFO qtp1776334754-54437 [org.apache.solr.core.SolrCore ] - [d-_v22_shard1_replica2] webapp=/solr path=/select params={fl=id,hive,timestamp,type,message_id,file_instance_id,score&sort=score+desc,+at+desc,+id+desc&indent=on&start=0&q=%2B(*)&wt=json&fq=%2Bhive:kduffie1+AND+%2Bchannel:(adam+aws+channel1+channel2+deploy+dev+no-reply+no-reply-aws+notifications+share+test+trouble)+AND+type:MESSAGE&rows=10} hits=2060 status=0 QTime=3
2014-06-12 21:34:26,176 INFO qtp1776334754-54437 [org.apache.solr.core.SolrCore ] - [d-_v22_shard1_replica2] webapp=/solr path=/select params={fl=id,hive,timestamp,type,message_id,file_instance_id,score&sort=score+desc,+at+desc,+id+desc&indent=on&start=0&q=%2B(*)&wt=json&fq=%2Bhive:kduffie1+AND+%2Bchannel:(adam+aws+channel1+channel2+deploy+dev+no-reply+no-reply-aws+notifications+share+test+trouble)+AND+type:IMAGE&rows=10} hits=127 status=0 QTime=2
2014-06-12 21:34:28,338 INFO qtp1776334754-44998 [org.apache.solr.core.SolrCore ] - [collection1] webapp=/solr path=/admin/ping params={} hits=0 status=0 QTime=0
2014-06-12 21:34:28,338 INFO qtp1776334754-44998 [org.apache.solr.core.SolrCore ] - [collection1] webapp=/solr path=/admin/ping params={} status=0 QTime=0
2014-06-12 21:34:34,338 INFO qtp1776334754-44998 [org.apache.solr.core.SolrCore ] - [collection1] webapp=/solr path=/admin/ping params={} hits=0 status=0 QTime=1
2014-06-12 21:34:34,338 INFO qtp1776334754-44998 [org.apache.solr.core.SolrCore ] - [collection1] webapp=/solr path=/admin/ping params={} status=0 QTime=1
2014-06-12 21:34:36,124 INFO qtp1776334754-54437 [org.apache.solr.core.SolrCore ] - [d-_v22_shard1_replica2] webapp=/solr path=/select params={fl=id,hive,timestamp,type,message_id,file_instance_id,score&sort=at+desc,+id+desc&indent=on&start=0&q=%2B(*)&wt=json&fq=%2Bhive:kduffie1+AND+%2Bchannel:(adam+aws+channel1+channel2+deploy+dev+no-reply+no-reply-aws+notifications+share+test+trouble)+AND+at:[2014-06-12T21:32:36Z+TO+*]&rows=300} hits=0 status=0 QTime=2
2014-06-12 21:34:39,213 INFO qtp1776334754-54437 [org.apache.solr.core.SolrCore ] - [d-_v22_shard1_replica2] webapp=/solr path=/select params={fl=id,hive,timestamp,type,message_id,file_instance_id,score&sort=score+desc,+at+desc,+id+desc&indent=on&start=0&q=%2B(%2B(*))+%2Bvotes:[1+TO+*]&wt=json&fq=%2Bhive:kduffie1+AND+%2Bchannel:(adam+aws+channel1+channel2+deploy+dev+no-reply+no-reply-aws+notifications+share+test+trouble)&rows=10} hits=0 status=0 QTime=2
2014-06-12 21:34:39,230 INFO qtp1776334754-54437 [org.apache.solr.core.SolrCore ] - [d-_v22_shard1_replica2] webapp=/solr path=/select params={fl=id,hive,timestamp,type,message_id,file_instance_id,score&sort=score+desc,+at+desc,+id+desc&indent=on&start=0&q=%2B(*)&wt=json&fq=%2Bhive:kduffie1+AND+%2Bchannel:(adam+aws+channel1+channel2+deploy+dev+no-reply+no-reply-aws+notifications+share+test+trouble)+AND+type:DOCUMENT&rows=10} hits=23 status=0 QTime=2
2014-06-12 21:34:39,912 INFO qtp1776334754-54437 [org.apache.solr.core.SolrCore ] - [d-_v22_shard1_replica2] webapp=/solr path=/select params={fl=id,hive,timestamp,type,message_id,file_instance_id,score&sort=score+desc,+at+desc,+id+desc&indent=on&start=0&q=message_id:2014-06-12-204d635d-6df3-479b-98ab-99affec787f5&wt=json&fq=%2Bhive:vtester&rows=100} hits=1 status=0 QTime=2
2014-06-12 21:34:40,337 INFO qtp1776334754-44998 [org.apache.solr.core.SolrCore ] - [collection1] webapp=/solr path=/admin/ping params={} hits=0 status=0 QTime=0
2014-06-12 21:34:40,337 INFO qtp1776334754-44998 [org.apache.solr.core.SolrCore ] - [collection1] webapp=/solr path=/admin/ping params={} status=0 QTime=0
2014-06-12 21:34:40,684 INFO qtp1776334754-54122 [solr.update.processor.LogUpdateProcessor] - [d-_v22_shard1_replica2] webapp=/solr path=/update params={distrib.from=http://10.220.16.204:8983/solr/d-_v22_shard1_replica1/&update.distrib=TOLEADER&wt=javabin&version=2} {add=[8d44b6e6-ab5f-4ce9-9270-82c74f3ed88d (1470742008879382528)]} 0 759
2014-06-12 21:34:40,865 INFO qtp1776334754-54122 [org.apache.solr.update.UpdateHandler ] - start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
2014-06-12 21:34:40,866 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: commit: start
2014-06-12 21:34:40,866 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: commit: enter lock
2014-06-12 21:34:40,866 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: commit: now prepare
2014-06-12 21:34:40,866 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: prepareCommit: flush
2014-06-12 21:34:40,866 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: index before flush _1be8(4.8):C3482/114:delGen=114 _1i99(4.8):C6015/1967:delGen=1752 _1kxp(4.8):C3344/98:delGen=98 _1lyh(4.8):C912/1:delGen=1 _1m34(4.8):C95/1:delGen=1 _1m35(4.8):C1 _1m37(4.8):C1 _1m39(4.8):C1 _1m3b(4.8):C1 _1m3c(4.8):C1 _1m3d(4.8):C1
2014-06-12 21:34:40,866 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DW][qtp1776334754-54122]: startFullFlush
2014-06-12 21:34:40,867 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DW][qtp1776334754-54122]: anyChanges? numDocsInRam=1 deletes=true hasTickets:false pendingChangesInFullFlush: false
2014-06-12 21:34:40,867 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DWFC][qtp1776334754-54122]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0, segment=_1m3e, aborting=false, numDocsInRAM=1, deleteQueue=DWDQ: [ generation: 122037 ]]
2014-06-12 21:34:40,867 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DWPT][qtp1776334754-54122]: flush postings as segment _1m3e numDocs=1
2014-06-12 21:34:40,926 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DWPT][qtp1776334754-54122]: new segment has 0 deleted docs
2014-06-12 21:34:40,927 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DWPT][qtp1776334754-54122]: new segment has no vectors; norms; no docValues; prox; freqs
2014-06-12 21:34:40,927 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DWPT][qtp1776334754-54122]: flushedFiles=[_1m3e_Lucene41_0.pos, _1m3e.fnm, _1m3e.fdx, _1m3e.nvd, _1m3e_Lucene41_0.tip, _1m3e.nvm, _1m3e_Lucene41_0.tim, _1m3e.fdt, _1m3e_Lucene41_0.doc]
2014-06-12 21:34:40,927 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DWPT][qtp1776334754-54122]: flushed codec=Lucene46
2014-06-12 21:34:40,927 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DWPT][qtp1776334754-54122]: flushed: segment=_1m3e ramUsed=1.096 MB newFlushedSize(includes docstores)=0.66 MB docs/MB=1.515
2014-06-12 21:34:40,928 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DW][qtp1776334754-54122]: publishFlushedSegment seg-private updates=null
2014-06-12 21:34:40,928 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: publishFlushedSegment
2014-06-12 21:34:40,928 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: push deletes 1 deleted terms (unique count=1) bytesUsed=1024 delGen=252435 packetCount=1 totBytesUsed=1024
2014-06-12 21:34:40,928 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: publish sets newSegment delGen=252436 seg=_1m3e(4.8):C1
2014-06-12 21:34:40,928 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IFD][qtp1776334754-54122]: now checkpoint "_1be8(4.8):C3482/114:delGen=114 _1i99(4.8):C6015/1967:delGen=1752 _1kxp(4.8):C3344/98:delGen=98 _1lyh(4.8):C912/1:delGen=1 _1m34(4.8):C95/1:delGen=1 _1m35(4.8):C1 _1m37(4.8):C1 _1m39(4.8):C1 _1m3b(4.8):C1 _1m3c(4.8):C1 _1m3d(4.8):C1 _1m3e(4.8):C1" [12 segments ; isCommit = false]
2014-06-12 21:34:40,929 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IFD][qtp1776334754-54122]: 0 msec to checkpoint
2014-06-12 21:34:40,929 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: apply all deletes during flush
2014-06-12 21:34:40,929 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: applyDeletes: infos=[_1be8(4.8):C3482/114:delGen=114, _1i99(4.8):C6015/1967:delGen=1752, _1kxp(4.8):C3344/98:delGen=98, _1lyh(4.8):C912/1:delGen=1, _1m34(4.8):C95/1:delGen=1, _1m35(4.8):C1, _1m37(4.8):C1, _1m39(4.8):C1, _1m3b(4.8):C1, _1m3c(4.8):C1, _1m3d(4.8):C1, _1m3e(4.8):C1] packetCount=1
2014-06-12 21:34:40,929 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: seg=_1m3d(4.8):C1 segGen=252433 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=1 100% deleted
2014-06-12 21:34:40,930 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: seg=_1m3c(4.8):C1 segGen=252433 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:40,930 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: seg=_1m3b(4.8):C1 segGen=252433 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:40,930 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: seg=_1m39(4.8):C1 segGen=252433 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:40,930 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: seg=_1m37(4.8):C1 segGen=252433 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:40,931 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: seg=_1m35(4.8):C1 segGen=252433 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:40,931 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: seg=_1m34(4.8):C95/1:delGen=1 segGen=252433 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:40,931 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: seg=_1lyh(4.8):C912/1:delGen=1 segGen=252433 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:40,931 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: seg=_1kxp(4.8):C3344/98:delGen=98 segGen=252433 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:40,931 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: seg=_1i99(4.8):C6015/1967:delGen=1752 segGen=252433 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:40,932 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: seg=_1be8(4.8):C3482/114:delGen=114 segGen=252433 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:40,932 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: applyDeletes took 3 msec
2014-06-12 21:34:40,932 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IFD][qtp1776334754-54122]: now checkpoint "_1be8(4.8):C3482/114:delGen=114 _1i99(4.8):C6015/1967:delGen=1752 _1kxp(4.8):C3344/98:delGen=98 _1lyh(4.8):C912/1:delGen=1 _1m34(4.8):C95/1:delGen=1 _1m35(4.8):C1 _1m37(4.8):C1 _1m39(4.8):C1 _1m3b(4.8):C1 _1m3c(4.8):C1 _1m3d(4.8):C1/1 _1m3e(4.8):C1" [12 segments ; isCommit = false]
2014-06-12 21:34:40,932 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IFD][qtp1776334754-54122]: 0 msec to checkpoint
2014-06-12 21:34:40,933 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: drop 100% deleted segments: _1m3d(4.8):C1/1
2014-06-12 21:34:40,933 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IFD][qtp1776334754-54122]: now checkpoint "_1be8(4.8):C3482/114:delGen=114 _1i99(4.8):C6015/1967:delGen=1752 _1kxp(4.8):C3344/98:delGen=98 _1lyh(4.8):C912/1:delGen=1 _1m34(4.8):C95/1:delGen=1 _1m35(4.8):C1 _1m37(4.8):C1 _1m39(4.8):C1 _1m3b(4.8):C1 _1m3c(4.8):C1 _1m3e(4.8):C1" [11 segments ; isCommit = false]
2014-06-12 21:34:40,933 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IFD][qtp1776334754-54122]: 0 msec to checkpoint
2014-06-12 21:34:40,933 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: prune sis=org.apache.lucene.index.SegmentInfos@3d0cd13f minGen=252437 packetCount=1
2014-06-12 21:34:40,933 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: pruneDeletes: prune 1 packets; 0 packets remain
2014-06-12 21:34:40,934 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DW][qtp1776334754-54122]: qtp1776334754-54122 finishFullFlush success=true
2014-06-12 21:34:40,934 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: findMerges: 11 segments
2014-06-12 21:34:40,935 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: seg=_1be8(4.8):C3482/114:delGen=114 size=33.378 MB
2014-06-12 21:34:40,936 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: seg=_1kxp(4.8):C3344/98:delGen=98 size=25.316 MB
2014-06-12 21:34:40,936 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: seg=_1i99(4.8):C6015/1967:delGen=1752 size=23.059 MB
2014-06-12 21:34:40,936 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: seg=_1lyh(4.8):C912/1:delGen=1 size=5.530 MB
2014-06-12 21:34:40,936 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: seg=_1m34(4.8):C95/1:delGen=1 size=1.201 MB [floored]
2014-06-12 21:34:40,937 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: seg=_1m3e(4.8):C1 size=0.660 MB [floored]
2014-06-12 21:34:40,937 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: seg=_1m35(4.8):C1 size=0.088 MB [floored]
2014-06-12 21:34:40,961 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: seg=_1m3c(4.8):C1 size=0.065 MB [floored]
2014-06-12 21:34:40,961 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: seg=_1m3b(4.8):C1 size=0.025 MB [floored]
2014-06-12 21:34:40,962 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: seg=_1m39(4.8):C1 size=0.015 MB [floored]
2014-06-12 21:34:40,962 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: seg=_1m37(4.8):C1 size=0.014 MB [floored]
2014-06-12 21:34:40,962 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [TMP][qtp1776334754-54122]: allowedSegmentCount=14 vs count=11 (eligible count=11) tooBigCount=0
2014-06-12 21:34:40,962 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [CMS][qtp1776334754-54122]: now merge
2014-06-12 21:34:40,962 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [CMS][qtp1776334754-54122]: index: _1be8(4.8):C3482/114:delGen=114 _1i99(4.8):C6015/1967:delGen=1752 _1kxp(4.8):C3344/98:delGen=98 _1lyh(4.8):C912/1:delGen=1 _1m34(4.8):C95/1:delGen=1 _1m35(4.8):C1 _1m37(4.8):C1 _1m39(4.8):C1 _1m3b(4.8):C1 _1m3c(4.8):C1 _1m3e(4.8):C1
2014-06-12 21:34:40,962 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [CMS][qtp1776334754-54122]: no more merges pending; now return
2014-06-12 21:34:40,962 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: startCommit(): start
2014-06-12 21:34:40,962 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: startCommit index=_1be8(4.8):C3482/114:delGen=114 _1i99(4.8):C6015/1967:delGen=1752 _1kxp(4.8):C3344/98:delGen=98 _1lyh(4.8):C912/1:delGen=1 _1m34(4.8):C95/1:delGen=1 _1m35(4.8):C1 _1m37(4.8):C1 _1m39(4.8):C1 _1m3b(4.8):C1 _1m3c(4.8):C1 _1m3e(4.8):C1 changeCount=226643
2014-06-12 21:34:41,022 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: done all syncs: [_1m35_Lucene41_0.doc, _1kxp.nvm, _1m37_Lucene41_0.pos, _1m37_Lucene41_0.tip, _1lyh_1.del, _1m37_Lucene41_0.tim, _1m35.nvd, _1be8.fdx, _1m35.nvm, _1m37.fnm, _1m3c_Lucene41_0.doc, _1kxp.fdx, _1be8.fdt, _1m37.fdx, _1m3b_Lucene41_0.doc, _1kxp.fdt, _1m37.fdt, _1m35_Lucene41_0.pos, _1m35_Lucene41_0.tip, _1m39.fnm, _1m35_Lucene41_0.tim, _1be8.si, _1kxp.si, _1m34.fnm, _1m3b_Lucene41_0.pos, _1m3c_Lucene41_0.pos, _1kxp.nvd, _1m3b.fdt, _1m3e_Lucene41_0.doc, _1m39_Lucene41_0.doc, _1m3b.fdx, _1kxp_Lucene41_0.pos, _1kxp.fnm, _1m39.fdx, _1lyh.fnm, _1m3c.nvd, _1i99_Lucene41_0.pos, _1m39.fdt, _1be8_36.del, _1m35.si, _1m3c.fnm, _1m3b.nvd, _1m3e_Lucene41_0.tip, _1kxp_Lucene41_0.doc, _1i99.fdt, _1m3e_Lucene41_0.tim, _1m34.fdx, _1kxp_2q.del, _1m3e.si, _1lyh_Lucene41_0.tip, _1m39.nvd, _1i99.fdx, _1lyh_Lucene41_0.tim, _1lyh.fdt, _1m3e.nvm, _1lyh.fdx, _1m34.fdt, _1m39.nvm, _1m3e.fdx, _1m3e.fdt, _1m3e.fnm, _1m3c.nvm, _1m34_Lucene41_0.tim, _1m3e.nvd, _1i99_Lucene41_0.doc, _1lyh.nvd, _1m3c_Lucene41_0.tim, _1m3c_Lucene41_0.tip, _1i99_1co.del, _1be8_Lucene41_0.doc, _1m3e_Lucene41_0.pos, _1be8.nvd, _1m35.fdx, _1m3b.nvm, _1lyh_Lucene41_0.doc, _1m3c.fdt, _1be8.nvm, _1m35.fdt, _1m37.nvm, _1m3c.fdx, _1m37_Lucene41_0.doc, _1m3b_Lucene41_0.tim, _1m37.nvd, _1i99.fnm, _1m3b_Lucene41_0.tip, _1m34_1.del, _1m34_Lucene41_0.tip, _1i99.nvm, _1m39_Lucene41_0.tip, _1m34_Lucene41_0.pos, _1m34.si, _1m39_Lucene41_0.tim, _1m34.nvd, _1i99.nvd, _1m35.fnm, _1be8_Lucene41_0.tip, _1m34.nvm, _1m37.si, _1m39_Lucene41_0.pos, _1i99.si, _1m3b.si, _1m3c.si, _1be8_Lucene41_0.tim, _1m34_Lucene41_0.doc, _1be8_Lucene41_0.pos, _1i99_Lucene41_0.tip, _1i99_Lucene41_0.tim, _1m3b.fnm, _1kxp_Lucene41_0.tim, _1be8.fnm, _1m39.si, _1kxp_Lucene41_0.tip, _1lyh.si, _1lyh.nvm, _1lyh_Lucene41_0.pos]
2014-06-12 21:34:41,022 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: commit: pendingCommit != null
2014-06-12 21:34:41,030 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: commit: wrote segments file "segments_1dkp"
2014-06-12 21:34:41,031 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IFD][qtp1776334754-54122]: now checkpoint "_1be8(4.8):C3482/114:delGen=114 _1i99(4.8):C6015/1967:delGen=1752 _1kxp(4.8):C3344/98:delGen=98 _1lyh(4.8):C912/1:delGen=1 _1m34(4.8):C95/1:delGen=1 _1m35(4.8):C1 _1m37(4.8):C1 _1m39(4.8):C1 _1m3b(4.8):C1 _1m3c(4.8):C1 _1m3e(4.8):C1" [11 segments ; isCommit = true]
2014-06-12 21:34:41,031 INFO qtp1776334754-54122 [org.apache.solr.core.SolrCore ] - SolrDeletionPolicy.onCommit: commits: num=2
commit{dir=NRTCachingDirectory(MMapDirectory@/local/solr/solr-4.8.0/example/solr/d-_v22_shard1_replica2/data/index lockFactory=NativeFSLockFactory@/local/solr/solr-4.8.0/example/solr/d-_v22_shard1_replica2/data/index; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1dko,generation=64248}
commit{dir=NRTCachingDirectory(MMapDirectory@/local/solr/solr-4.8.0/example/solr/d-_v22_shard1_replica2/data/index lockFactory=NativeFSLockFactory@/local/solr/solr-4.8.0/example/solr/d-_v22_shard1_replica2/data/index; maxCacheMB=48.0 maxMergeSizeMB=4.0),segFN=segments_1dkp,generation=64249}
2014-06-12 21:34:41,031 INFO qtp1776334754-54122 [org.apache.solr.core.SolrCore ] - newest commit generation = 64249
2014-06-12 21:34:41,031 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IFD][qtp1776334754-54122]: deleteCommits: now decRef commit "segments_1dko"
2014-06-12 21:34:41,031 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IFD][qtp1776334754-54122]: delete "segments_1dko"
2014-06-12 21:34:41,031 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IFD][qtp1776334754-54122]: 0 msec to checkpoint
2014-06-12 21:34:41,031 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: commit: done
2014-06-12 21:34:41,032 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DW][qtp1776334754-54122]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
2014-06-12 21:34:41,033 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: nrtIsCurrent: infoVersion matches: false; DW changes: false; BD changes: false
2014-06-12 21:34:41,033 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: flush at getReader
2014-06-12 21:34:41,033 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DW][qtp1776334754-54122]: startFullFlush
2014-06-12 21:34:41,033 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DW][qtp1776334754-54122]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
2014-06-12 21:34:41,033 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: apply all deletes during flush
2014-06-12 21:34:41,033 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: applyDeletes: no deletes; skipping
2014-06-12 21:34:41,034 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54122]: prune sis=org.apache.lucene.index.SegmentInfos@3d0cd13f minGen=252437 packetCount=0
2014-06-12 21:34:41,036 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: return reader version=167551 reader=StandardDirectoryReader(segments_1dkp:167551:nrt _1be8(4.8):C3482/114:delGen=114 _1i99(4.8):C6015/1967:delGen=1752 _1kxp(4.8):C3344/98:delGen=98 _1lyh(4.8):C912/1:delGen=1 _1m34(4.8):C95/1:delGen=1 _1m35(4.8):C1 _1m37(4.8):C1 _1m39(4.8):C1 _1m3b(4.8):C1 _1m3c(4.8):C1 _1m3e(4.8):C1)
2014-06-12 21:34:41,036 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DW][qtp1776334754-54122]: qtp1776334754-54122 finishFullFlush success=true
2014-06-12 21:34:41,036 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: getReader took 3 msec
2014-06-12 21:34:41,036 INFO qtp1776334754-54122 [org.apache.solr.search.SolrIndexSearcher] - Opening Searcher@17fe42c1[d-_v22_shard1_replica2] main
2014-06-12 21:34:41,037 INFO searcherExecutor-32-thread-1 [org.apache.solr.core.SolrCore ] - QuerySenderListener sending requests to Searcher@17fe42c1[d-_v22_shard1_replica2] main{StandardDirectoryReader(segments_1dkp:167551:nrt _1be8(4.8):C3482/114:delGen=114 _1i99(4.8):C6015/1967:delGen=1752 _1kxp(4.8):C3344/98:delGen=98 _1lyh(4.8):C912/1:delGen=1 _1m34(4.8):C95/1:delGen=1 _1m35(4.8):C1 _1m37(4.8):C1 _1m39(4.8):C1 _1m3b(4.8):C1 _1m3c(4.8):C1 _1m3e(4.8):C1)}
2014-06-12 21:34:41,037 INFO searcherExecutor-32-thread-1 [org.apache.solr.core.SolrCore ] - QuerySenderListener done.
2014-06-12 21:34:41,038 INFO searcherExecutor-32-thread-1 [org.apache.solr.core.SolrCore ] - [d-_v22_shard1_replica2] Registered new searcher Searcher@17fe42c1[d-_v22_shard1_replica2] main{StandardDirectoryReader(segments_1dkp:167551:nrt _1be8(4.8):C3482/114:delGen=114 _1i99(4.8):C6015/1967:delGen=1752 _1kxp(4.8):C3344/98:delGen=98 _1lyh(4.8):C912/1:delGen=1 _1m34(4.8):C95/1:delGen=1 _1m35(4.8):C1 _1m37(4.8):C1 _1m39(4.8):C1 _1m3b(4.8):C1 _1m3c(4.8):C1 _1m3e(4.8):C1)}
2014-06-12 21:34:41,038 INFO searcherExecutor-32-thread-1 [org.apache.solr.update.LoggingInfoStream] - [IFD][searcherExecutor-32-thread-1]: delete "_1m3d_Lucene41_0.pos"
2014-06-12 21:34:41,038 INFO searcherExecutor-32-thread-1 [org.apache.solr.update.LoggingInfoStream] - [IFD][searcherExecutor-32-thread-1]: delete "_1m3d.nvd"
2014-06-12 21:34:41,038 INFO searcherExecutor-32-thread-1 [org.apache.solr.update.LoggingInfoStream] - [IFD][searcherExecutor-32-thread-1]: delete "_1m3d.fdt"
2014-06-12 21:34:41,039 INFO searcherExecutor-32-thread-1 [org.apache.solr.update.LoggingInfoStream] - [IFD][searcherExecutor-32-thread-1]: delete "_1m3d.fdx"
2014-06-12 21:34:41,039 INFO searcherExecutor-32-thread-1 [org.apache.solr.update.LoggingInfoStream] - [IFD][searcherExecutor-32-thread-1]: delete "_1m3d_Lucene41_0.doc"
2014-06-12 21:34:41,039 INFO searcherExecutor-32-thread-1 [org.apache.solr.update.LoggingInfoStream] - [IFD][searcherExecutor-32-thread-1]: delete "_1m3d.fnm"
2014-06-12 21:34:41,039 INFO searcherExecutor-32-thread-1 [org.apache.solr.update.LoggingInfoStream] - [IFD][searcherExecutor-32-thread-1]: delete "_1m3d.si"
2014-06-12 21:34:41,039 INFO searcherExecutor-32-thread-1 [org.apache.solr.update.LoggingInfoStream] - [IFD][searcherExecutor-32-thread-1]: delete "_1m3d.nvm"
2014-06-12 21:34:41,039 INFO searcherExecutor-32-thread-1 [org.apache.solr.update.LoggingInfoStream] - [IFD][searcherExecutor-32-thread-1]: delete "_1m3d_Lucene41_0.tip"
2014-06-12 21:34:41,039 INFO searcherExecutor-32-thread-1 [org.apache.solr.update.LoggingInfoStream] - [IFD][searcherExecutor-32-thread-1]: delete "_1m3d_Lucene41_0.tim"
2014-06-12 21:34:41,040 INFO qtp1776334754-54122 [org.apache.solr.update.UpdateHandler ] - end_commit_flush
2014-06-12 21:34:41,040 INFO qtp1776334754-54122 [solr.update.processor.LogUpdateProcessor] - [d-_v22_shard1_replica2] webapp=/solr path=/update params={waitSearcher=true&distrib.from=http://10.220.16.204:8983/solr/d-_v22_shard1_replica1/&update.distrib=FROMLEADER&openSearcher=true&commit=true&wt=javabin&expungeDeletes=false&commit_end_point=true&version=2&softCommit=false} {commit=} 0 175
2014-06-12 21:34:46,140 INFO qtp1776334754-54437 [org.apache.solr.core.SolrCore ] - [d-_v22_shard1_replica2] webapp=/solr path=/select params={fl=id,hive,timestamp,type,message_id,file_instance_id,score&sort=at+desc,+id+desc&indent=on&start=0&q=%2B(*)&wt=json&fq=%2Bhive:kduffie1+AND+%2Bchannel:(adam+aws+channel1+channel2+deploy+dev+no-reply+no-reply-aws+notifications+share+test+trouble)+AND+at:[2014-06-12T21:32:46Z+TO+*]&rows=300} hits=0 status=0 QTime=2
2014-06-12 21:34:46,158 INFO qtp1776334754-54437 [org.apache.solr.core.SolrCore ] - [d-_v22_shard1_replica2] webapp=/solr path=/select params={fl=id,hive,timestamp,type,message_id,file_instance_id,score&sort=score+desc,+at+desc,+id+desc&indent=on&start=0&q=%2B(*)&wt=json&fq=%2Bhive:kduffie1+AND+%2Bchannel:(adam+aws+channel1+channel2+deploy+dev+no-reply+no-reply-aws+notifications+share+test+trouble)+AND+type:MESSAGE&rows=10} hits=2060 status=0 QTime=3
2014-06-12 21:34:46,174 INFO qtp1776334754-54437 [org.apache.solr.core.SolrCore ] - [d-_v22_shard1_replica2] webapp=/solr path=/select params={fl=id,hive,timestamp,type,message_id,file_instance_id,score&sort=score+desc,+at+desc,+id+desc&indent=on&start=0&q=%2B(*)&wt=json&fq=%2Bhive:kduffie1+AND+%2Bchannel:(adam+aws+channel1+channel2+deploy+dev+no-reply+no-reply-aws+notifications+share+test+trouble)+AND+type:IMAGE&rows=10} hits=127 status=0 QTime=2
2014-06-12 21:34:46,338 INFO qtp1776334754-44998 [org.apache.solr.core.SolrCore ] - [collection1] webapp=/solr path=/admin/ping params={} hits=0 status=0 QTime=0
2014-06-12 21:34:46,338 INFO qtp1776334754-44998 [org.apache.solr.core.SolrCore ] - [collection1] webapp=/solr path=/admin/ping params={} status=0 QTime=0
2014-06-12 21:34:52,338 INFO qtp1776334754-44998 [org.apache.solr.core.SolrCore ] - [collection1] webapp=/solr path=/admin/ping params={} hits=0 status=0 QTime=1
2014-06-12 21:34:52,338 INFO qtp1776334754-44998 [org.apache.solr.core.SolrCore ] - [collection1] webapp=/solr path=/admin/ping params={} status=0 QTime=1
2014-06-12 21:34:56,026 INFO qtp1776334754-54437 [org.apache.solr.core.SolrCore ] - [d-_v22_shard1_replica2] webapp=/solr path=/select params={fl=id,hive,timestamp,type,message_id,file_instance_id,score&sort=score+desc,+at+desc,+id+desc&indent=on&start=0&q=message_id:2014-06-12-204d635d-6df3-479b-98ab-99affec787f5&wt=json&fq=%2Bhive:vtester&rows=100} hits=1 status=0 QTime=2
2014-06-12 21:34:56,037 INFO qtp1776334754-54122 [solr.update.processor.LogUpdateProcessor] - [d-_v22_shard1_replica2] webapp=/solr path=/update params={distrib.from=http://10.220.16.204:8983/solr/d-_v22_shard1_replica1/&update.distrib=TOLEADER&wt=javabin&version=2} {} 0 0
2014-06-12 21:34:56,038 ERROR qtp1776334754-54122 [org.apache.solr.core.SolrCore ] - org.apache.solr.common.SolrException: [doc=8d44b6e6-ab5f-4ce9-9270-82c74f3ed88d] missing required field: timestamp
at org.apache.solr.update.DocumentBuilder.toDocument(DocumentBuilder.java:189)
at org.apache.solr.update.AddUpdateCommand.getLuceneDocument(AddUpdateCommand.java:77)
at org.apache.solr.update.DirectUpdateHandler2.addDoc0(DirectUpdateHandler2.java:234)
at org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:160)
at org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:69)
at org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:51)
at org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalAdd(DistributedUpdateProcessor.java:704)
at org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:858)
at org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:557)
at org.apache.solr.update.processor.LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:100)
at org.apache.solr.handler.loader.JavabinLoader$1.update(JavabinLoader.java:96)
at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec$1.readOuterMostDocIterator(JavaBinUpdateRequestCodec.java:166)
at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec$1.readIterator(JavaBinUpdateRequestCodec.java:136)
at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:225)
at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec$1.readNamedList(JavaBinUpdateRequestCodec.java:121)
at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:190)
at org.apache.solr.common.util.JavaBinCodec.unmarshal(JavaBinCodec.java:116)
at org.apache.solr.client.solrj.request.JavaBinUpdateRequestCodec.unmarshal(JavaBinUpdateRequestCodec.java:173)
at org.apache.solr.handler.loader.JavabinLoader.parseAndLoadDocs(JavabinLoader.java:106)
at org.apache.solr.handler.loader.JavabinLoader.load(JavabinLoader.java:58)
at org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:92)
at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:74)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:135)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:1952)
at org.apache.solr.servlet.SolrDispatchFilter.execute(SolrDispatchFilter.java:774)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:418)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:207)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1419)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:455)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:557)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:231)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1075)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:384)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1009)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:255)
at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:154)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
at org.eclipse.jetty.server.Server.handle(Server.java:368)
at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:489)
at org.eclipse.jetty.server.BlockingHttpConnection.handleRequest(BlockingHttpConnection.java:53)
at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:953)
at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1014)
at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:953)
at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:240)
at org.eclipse.jetty.server.BlockingHttpConnection.handle(BlockingHttpConnection.java:72)
at org.eclipse.jetty.server.bio.SocketConnector$ConnectorEndPoint.run(SocketConnector.java:264)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
at java.lang.Thread.run(Thread.java:745)
2014-06-12 21:34:56,050 INFO qtp1776334754-54122 [org.apache.solr.update.UpdateHandler ] - start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
2014-06-12 21:34:56,050 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DW][qtp1776334754-54122]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
2014-06-12 21:34:56,051 INFO qtp1776334754-54122 [org.apache.solr.update.UpdateHandler ] - No uncommitted changes. Skipping IW.commit.
2014-06-12 21:34:56,051 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DW][qtp1776334754-54122]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
2014-06-12 21:34:56,051 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54122]: nrtIsCurrent: infoVersion matches: true; DW changes: false; BD changes: false
2014-06-12 21:34:56,052 INFO qtp1776334754-54122 [org.apache.solr.update.LoggingInfoStream] - [DW][qtp1776334754-54122]: anyChanges? numDocsInRam=0 deletes=false hasTickets:false pendingChangesInFullFlush: false
2014-06-12 21:34:56,052 INFO qtp1776334754-54122 [org.apache.solr.core.SolrCore ] - SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher
2014-06-12 21:34:56,052 INFO qtp1776334754-54122 [org.apache.solr.update.UpdateHandler ] - end_commit_flush
2014-06-12 21:34:56,052 INFO qtp1776334754-54122 [solr.update.processor.LogUpdateProcessor] - [d-_v22_shard1_replica2] webapp=/solr path=/update params={waitSearcher=true&distrib.from=http://10.220.16.204:8983/solr/d-_v22_shard1_replica1/&update.distrib=FROMLEADER&openSearcher=true&commit=true&wt=javabin&expungeDeletes=false&commit_end_point=true&version=2&softCommit=false} {commit=} 0 2
2014-06-12 21:34:56,126 INFO qtp1776334754-54437 [org.apache.solr.core.SolrCore ] - [d-_v22_shard1_replica2] webapp=/solr path=/select params={fl=id,hive,timestamp,type,message_id,file_instance_id,score&sort=at+desc,+id+desc&indent=on&start=0&q=%2B(*)&wt=json&fq=%2Bhive:kduffie1+AND+%2Bchannel:(adam+aws+channel1+channel2+deploy+dev+no-reply+no-reply-aws+notifications+share+test+trouble)+AND+at:[2014-06-12T21:32:56Z+TO+*]&rows=300} hits=0 status=0 QTime=2
2014-06-12 21:34:56,138 INFO qtp1776334754-54437 [org.apache.solr.core.SolrCore ] - [d-_v22_shard1_replica2] webapp=/solr path=/select params={fl=id,hive,timestamp,type,message_id,file_instance_id,score&sort=score+desc,+at+desc,+id+desc&indent=on&start=0&q=%2B(*)&wt=json&fq=%2Bhive:kduffie1+AND+%2Bchannel:(adam+aws+channel1+channel2+deploy+dev+no-reply+no-reply-aws+notifications+share+test+trouble)+AND+type:MESSAGE&rows=10} hits=2060 status=0 QTime=1
2014-06-12 21:34:56,149 INFO qtp1776334754-54437 [org.apache.solr.core.SolrCore ] - [d-_v22_shard1_replica2] webapp=/solr path=/select params={fl=id,hive,timestamp,type,message_id,file_instance_id,score&sort=score+desc,+at+desc,+id+desc&indent=on&start=0&q=%2B(*)&wt=json&fq=%2Bhive:kduffie1+AND+%2Bchannel:(adam+aws+channel1+channel2+deploy+dev+no-reply+no-reply-aws+notifications+share+test+trouble)+AND+type:IMAGE&rows=10} hits=127 status=0 QTime=0
2014-06-12 21:34:57,804 INFO qtp1776334754-54529 [org.apache.solr.update.UpdateHandler ] - start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
2014-06-12 21:34:57,805 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54529]: commit: start
2014-06-12 21:34:57,805 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54529]: commit: enter lock
2014-06-12 21:34:57,805 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54529]: commit: now prepare
2014-06-12 21:34:57,806 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54529]: prepareCommit: flush
2014-06-12 21:34:57,806 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54529]: index before flush _1be8(4.8):C3482/114:delGen=114 _1i99(4.8):C6015/1967:delGen=1752 _1kxp(4.8):C3344/98:delGen=98 _1lyh(4.8):C912/1:delGen=1 _1m34(4.8):C95/1:delGen=1 _1m35(4.8):C1 _1m37(4.8):C1 _1m39(4.8):C1 _1m3b(4.8):C1 _1m3c(4.8):C1 _1m3e(4.8):C1
2014-06-12 21:34:57,806 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [DW][qtp1776334754-54529]: startFullFlush
2014-06-12 21:34:57,806 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [DW][qtp1776334754-54529]: anyChanges? numDocsInRam=1 deletes=true hasTickets:false pendingChangesInFullFlush: false
2014-06-12 21:34:57,806 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [DWFC][qtp1776334754-54529]: addFlushableState DocumentsWriterPerThread [pendingDeletes=gen=0, segment=_1m3f, aborting=false, numDocsInRAM=1, deleteQueue=DWDQ: [ generation: 122039 ]]
2014-06-12 21:34:57,806 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [DWPT][qtp1776334754-54529]: flush postings as segment _1m3f numDocs=1
2014-06-12 21:34:57,811 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [DWPT][qtp1776334754-54529]: new segment has 0 deleted docs
2014-06-12 21:34:57,811 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [DWPT][qtp1776334754-54529]: new segment has no vectors; norms; no docValues; prox; freqs
2014-06-12 21:34:57,811 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [DWPT][qtp1776334754-54529]: flushedFiles=[_1m3f_Lucene41_0.doc, _1m3f.fnm, _1m3f.fdx, _1m3f.nvm, _1m3f_Lucene41_0.tip, _1m3f_Lucene41_0.pos, _1m3f.fdt, _1m3f.nvd, _1m3f_Lucene41_0.tim]
2014-06-12 21:34:57,811 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [DWPT][qtp1776334754-54529]: flushed codec=Lucene46
2014-06-12 21:34:57,811 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [DWPT][qtp1776334754-54529]: flushed: segment=_1m3f ramUsed=0.153 MB newFlushedSize(includes docstores)=0.02 MB docs/MB=48.962
2014-06-12 21:34:57,812 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [DW][qtp1776334754-54529]: publishFlushedSegment seg-private updates=null
2014-06-12 21:34:57,812 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54529]: publishFlushedSegment
2014-06-12 21:34:57,812 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54529]: push deletes 1 deleted terms (unique count=1) bytesUsed=1024 delGen=252439 packetCount=1 totBytesUsed=1024
2014-06-12 21:34:57,812 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54529]: publish sets newSegment delGen=252440 seg=_1m3f(4.8):C1
2014-06-12 21:34:57,812 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [IFD][qtp1776334754-54529]: now checkpoint "_1be8(4.8):C3482/114:delGen=114 _1i99(4.8):C6015/1967:delGen=1752 _1kxp(4.8):C3344/98:delGen=98 _1lyh(4.8):C912/1:delGen=1 _1m34(4.8):C95/1:delGen=1 _1m35(4.8):C1 _1m37(4.8):C1 _1m39(4.8):C1 _1m3b(4.8):C1 _1m3c(4.8):C1 _1m3e(4.8):C1 _1m3f(4.8):C1" [12 segments ; isCommit = false]
2014-06-12 21:34:57,813 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [IFD][qtp1776334754-54529]: 0 msec to checkpoint
2014-06-12 21:34:57,813 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [IW][qtp1776334754-54529]: apply all deletes during flush
2014-06-12 21:34:57,813 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54529]: applyDeletes: infos=[_1be8(4.8):C3482/114:delGen=114, _1i99(4.8):C6015/1967:delGen=1752, _1kxp(4.8):C3344/98:delGen=98, _1lyh(4.8):C912/1:delGen=1, _1m34(4.8):C95/1:delGen=1, _1m35(4.8):C1, _1m37(4.8):C1, _1m39(4.8):C1, _1m3b(4.8):C1, _1m3c(4.8):C1, _1m3e(4.8):C1, _1m3f(4.8):C1] packetCount=1
2014-06-12 21:34:57,813 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54529]: seg=_1m3e(4.8):C1 segGen=252437 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:57,814 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54529]: seg=_1m3c(4.8):C1 segGen=252437 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:57,814 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54529]: seg=_1m3b(4.8):C1 segGen=252437 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:57,814 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54529]: seg=_1m39(4.8):C1 segGen=252437 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:57,814 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54529]: seg=_1m37(4.8):C1 segGen=252437 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:57,814 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54529]: seg=_1m35(4.8):C1 segGen=252437 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:57,815 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54529]: seg=_1m34(4.8):C95/1:delGen=1 segGen=252437 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:57,815 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54529]: seg=_1lyh(4.8):C912/1:delGen=1 segGen=252437 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:57,815 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54529]: seg=_1kxp(4.8):C3344/98:delGen=98 segGen=252437 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:57,815 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54529]: seg=_1i99(4.8):C6015/1967:delGen=1752 segGen=252437 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:57,815 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54529]: seg=_1be8(4.8):C3482/114:delGen=114 segGen=252437 coalesced deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)] newDelCount=0
2014-06-12 21:34:57,816 INFO qtp1776334754-54529 [org.apache.solr.update.LoggingInfoStream] - [BD][qtp1776334754-54529]: applyDeletes took 3 msec
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment