Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/11580/
Java: 32bit/jdk1.8.0_20 -server -XX:+UseParallelGC (asserts: false)
1 tests failed.
FAILED:
org.apache.solr.client.solrj.embedded.SolrExampleJettyTest.testArbitraryJsonIndexing
Error Message:
Stack Trace:
java.lang.NullPointerException
at
__randomizedtesting.SeedInfo.seed([C023B9FA8AD756D4:343CA3514BB843BC]:0)
at org.noggit.JSONParser.<init>(JSONParser.java:131)
at org.noggit.ObjectBuilder.fromJSON(ObjectBuilder.java:32)
at
org.apache.solr.client.solrj.embedded.SolrExampleJettyTest.testArbitraryJsonIndexing(SolrExampleJettyTest.java:91)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:483)
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:54)
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 10926 lines...]
[junit4] Suite: org.apache.solr.client.solrj.embedded.SolrExampleJettyTest
[junit4] 2> Creating dataDir:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/init-core-data-001
[junit4] 2> 126424 T416 oas.SolrTestCaseJ4.initCore ####initCore
[junit4] 2> 126424 T416 oas.SolrTestCaseJ4.initCore ####initCore end
[junit4] 2> 126425 T416 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 126428 T416 oejs.AbstractConnector.doStart Started
[email protected]:55949
[junit4] 2> 126428 T416 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()
[junit4] 2> 126429 T416 oasc.SolrResourceLoader.locateSolrHome JNDI not
configured for solr (NoInitialContextEx)
[junit4] 2> 126429 T416 oasc.SolrResourceLoader.locateSolrHome using
system property solr.solr.home:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001
[junit4] 2> 126429 T416 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/'
[junit4] 2> 126439 T416 oasc.ConfigSolr.fromFile Loading container
configuration from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/solr.xml
[junit4] 2> 126445 T416 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001
[junit4] 2> 126445 T416 oasc.CoreContainer.<init> New CoreContainer 6050143
[junit4] 2> 126445 T416 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/]
[junit4] 2> 126446 T416 oashc.HttpShardHandlerFactory.getParameter Setting
socketTimeout to: 0
[junit4] 2> 126446 T416 oashc.HttpShardHandlerFactory.getParameter Setting
urlScheme to: null
[junit4] 2> 126446 T416 oashc.HttpShardHandlerFactory.getParameter Setting
connTimeout to: 0
[junit4] 2> 126447 T416 oashc.HttpShardHandlerFactory.getParameter Setting
maxConnectionsPerHost to: 20
[junit4] 2> 126447 T416 oashc.HttpShardHandlerFactory.getParameter Setting
maxConnections to: 10000
[junit4] 2> 126447 T416 oashc.HttpShardHandlerFactory.getParameter Setting
corePoolSize to: 0
[junit4] 2> 126447 T416 oashc.HttpShardHandlerFactory.getParameter Setting
maximumPoolSize to: 2147483647
[junit4] 2> 126447 T416 oashc.HttpShardHandlerFactory.getParameter Setting
maxThreadIdleTime to: 5
[junit4] 2> 126448 T416 oashc.HttpShardHandlerFactory.getParameter Setting
sizeOfQueue to: -1
[junit4] 2> 126448 T416 oashc.HttpShardHandlerFactory.getParameter Setting
fairnessPolicy to: false
[junit4] 2> 126448 T416 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 126449 T416 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 126449 T416 oasl.LogWatcher.newRegisteredLogWatcher
Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 126449 T416 oasc.CoreContainer.load Host Name:
[junit4] 2> 126451 T416 oasc.CorePropertiesLocator.discover Looking for
core definitions underneath
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001
[junit4] 2> 126452 T416 oasc.CorePropertiesLocator.discoverUnder Found
core collection1 in
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/collection1/
[junit4] 2> 126452 T416 oasc.CorePropertiesLocator.discover Found 1 core
definitions
[junit4] 2> 126454 T426 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/collection1/'
[junit4] 2> 126469 T426 oasc.SolrConfig.initLibs Adding specified lib dirs
to ClassLoader
[junit4] 2> 126469 T426 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader:
../../../contrib/extraction/lib (resolved as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/collection1/../../../contrib/extraction/lib).
[junit4] 2> 126470 T426 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader: ../../../dist/ (resolved
as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/collection1/../../../dist).
[junit4] 2> 126470 T426 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader:
../../../contrib/clustering/lib/ (resolved as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/collection1/../../../contrib/clustering/lib).
[junit4] 2> 126470 T426 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader: ../../../dist/ (resolved
as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/collection1/../../../dist).
[junit4] 2> 126470 T426 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader:
../../../contrib/langid/lib/ (resolved as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/collection1/../../../contrib/langid/lib).
[junit4] 2> 126470 T426 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader: ../../../dist/ (resolved
as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/collection1/../../../dist).
[junit4] 2> 126471 T426 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader:
../../../contrib/velocity/lib (resolved as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/collection1/../../../contrib/velocity/lib).
[junit4] 2> 126471 T426 oasc.SolrResourceLoader.addToClassLoader WARN
Can't find (or read) directory to add to classloader: ../../../dist/ (resolved
as:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/collection1/../../../dist).
[junit4] 2> 126485 T426 oasu.SolrIndexConfig.<init> IndexWriter infoStream
solr logging is enabled
[junit4] 2> 126487 T426 oasc.SolrConfig.<init> Using Lucene MatchVersion:
5.0.0
[junit4] 2> 126513 T426 oasc.SolrConfig.<init> Loaded SolrConfig:
solrconfig.xml
[junit4] 2> 126514 T426 oass.IndexSchema.readSchema Reading Solr Schema
from
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/collection1/conf/schema.xml
[junit4] 2> 126518 T426 oass.IndexSchema.readSchema [collection1] Schema
name=example
[junit4] 2> 126689 T426 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 126690 T426 oasr.RestManager$Registry.registerManagedResource
Registered ManagedResource impl
org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path
/schema/analysis/stopwords/english
[junit4] 2> 126691 T426 oasr.RestManager$Registry.registerManagedResource
Registered ManagedResource impl
org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory$SynonymManager
for path /schema/analysis/synonyms/english
[junit4] 2> 126696 T426 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 126698 T426 oass.FileExchangeRateProvider.reload Reloading
exchange rates from file currency.xml
[junit4] 2> 126712 T426 oasc.CoreContainer.create Creating SolrCore
'collection1' using configuration from instancedir
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/collection1/
[junit4] 2> 126712 T426 oasc.SolrCore.initDirectoryFactory
org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 126712 T426 oasc.SolrCore.<init> [collection1] Opening new
SolrCore at
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/collection1/,
dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-002/
[junit4] 2> 126713 T426 oasc.JmxMonitoredMap.<init> JMX monitoring is
enabled. Adding Solr mbeans to JMX Server:
com.sun.jmx.mbeanserver.JmxMBeanServer@1d3041c
[junit4] 2> 126713 T426 oasc.SolrCore.initListeners [collection1] Added
SolrEventListener for newSearcher:
org.apache.solr.core.QuerySenderListener{queries=[]}
[junit4] 2> 126713 T426 oasc.SolrCore.initListeners [collection1] Added
SolrEventListener for firstSearcher:
org.apache.solr.core.QuerySenderListener{queries=[{q=static firstSearcher
warming in solrconfig.xml}]}
[junit4] 2> 126714 T426 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-002
[junit4] 2> 126714 T426 oasc.SolrCore.getNewIndexDir New index directory
detected: old=null
new=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-002/index/
[junit4] 2> 126714 T426 oasc.SolrCore.initIndex WARN [collection1] Solr
index directory
'/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-002/index'
doesn't exist. Creating new index...
[junit4] 2> 126714 T426 oasc.CachingDirectoryFactory.get return new
directory for
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-002/index
[junit4] 2> 126714 T426 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-193-thread-1]: init: current segments file is
"segments";
deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@e1c482
[junit4] 2> 126714 T426 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-193-thread-1]: now checkpoint "" [0 segments ; isCommit
= false]
[junit4] 2> 126715 T426 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-193-thread-1]: 0 msec to checkpoint
[junit4] 2> 126715 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: init: create=true
[junit4] 2> 126715 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]:
[junit4] 2> dir=BaseDirectoryWrapper(RAMDirectory@168b0b
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1dc7623)
[junit4] 2> index=
[junit4] 2> version=5.0.0
[junit4] 2> analyzer=null
[junit4] 2> ramBufferSizeMB=100.0
[junit4] 2> maxBufferedDocs=-1
[junit4] 2> maxBufferedDeleteTerms=-1
[junit4] 2> mergedSegmentWarmer=null
[junit4] 2>
delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper
[junit4] 2> commit=null
[junit4] 2> openMode=CREATE
[junit4] 2>
similarity=org.apache.lucene.search.similarities.DefaultSimilarity
[junit4] 2> mergeScheduler=ConcurrentMergeScheduler:
maxThreadCount=1, maxMergeCount=2, mergeThreadPriority=-1
[junit4] 2> default WRITE_LOCK_TIMEOUT=1000
[junit4] 2> writeLockTimeout=1000
[junit4] 2> codec=Lucene50
[junit4] 2> infoStream=org.apache.solr.update.LoggingInfoStream
[junit4] 2> mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10,
maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0,
forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2>
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@1c8248e
[junit4] 2> readerPooling=false
[junit4] 2> perThreadHardLimitMB=1945
[junit4] 2> useCompoundFile=false
[junit4] 2> commitOnClose=true
[junit4] 2> writer=org.apache.lucene.util.SetOnce@1e8a66f
[junit4] 2>
[junit4] 2> 126715 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: now flush at close
[junit4] 2> 126715 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: start flush: applyAllDeletes=true
[junit4] 2> 126715 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: index before flush
[junit4] 2> 126716 T426 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-193-thread-1]: startFullFlush
[junit4] 2> 126716 T426 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-193-thread-1]: coreLoadExecutor-193-thread-1
finishFullFlush success=true
[junit4] 2> 126716 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: apply all deletes during flush
[junit4] 2> 126716 T426 oasu.LoggingInfoStream.message
[BD][coreLoadExecutor-193-thread-1]: prune sis=segments:
minGen=9223372036854775807 packetCount=0
[junit4] 2> 126716 T426 oasu.LoggingInfoStream.message
[CMS][coreLoadExecutor-193-thread-1]: now merge
[junit4] 2> 126716 T426 oasu.LoggingInfoStream.message
[CMS][coreLoadExecutor-193-thread-1]: index:
[junit4] 2> 126716 T426 oasu.LoggingInfoStream.message
[CMS][coreLoadExecutor-193-thread-1]: no more merges pending; now return
[junit4] 2> 126717 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: waitForMerges
[junit4] 2> 126717 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: waitForMerges done
[junit4] 2> 126717 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: commit: start
[junit4] 2> 126717 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: commit: enter lock
[junit4] 2> 126717 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: commit: now prepare
[junit4] 2> 126717 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: prepareCommit: flush
[junit4] 2> 126717 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: index before flush
[junit4] 2> 126717 T426 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-193-thread-1]: startFullFlush
[junit4] 2> 126717 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: apply all deletes during flush
[junit4] 2> 126718 T426 oasu.LoggingInfoStream.message
[BD][coreLoadExecutor-193-thread-1]: prune sis=segments:
minGen=9223372036854775807 packetCount=0
[junit4] 2> 126718 T426 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-193-thread-1]: coreLoadExecutor-193-thread-1
finishFullFlush success=true
[junit4] 2> 126718 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: startCommit(): start
[junit4] 2> 126718 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: startCommit index= changeCount=1
[junit4] 2> 126718 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: done all syncs: []
[junit4] 2> 126718 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: commit: pendingCommit != null
[junit4] 2> 126719 T426 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-193-thread-1]: now checkpoint "" [0 segments ; isCommit
= true]
[junit4] 2> 126719 T426 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=BaseDirectoryWrapper(RAMDirectory@168b0b
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1dc7623),segFN=segments_1,generation=1}
[junit4] 2> 126719 T426 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 126719 T426 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-193-thread-1]: 0 msec to checkpoint
[junit4] 2> 126719 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: commit: wrote segments file "segments_1"
[junit4] 2> 126719 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: commit: took 2.2 msec
[junit4] 2> 126719 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: commit: done
[junit4] 2> 126720 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: rollback
[junit4] 2> 126720 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: all running merges have aborted
[junit4] 2> 126720 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: rollback: done finish merges
[junit4] 2> 126720 T426 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-193-thread-1]: abort
[junit4] 2> 126720 T426 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-193-thread-1]: done abort success=true
[junit4] 2> 126720 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: rollback: infos=
[junit4] 2> 126720 T426 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-193-thread-1]: now checkpoint "" [0 segments ; isCommit
= false]
[junit4] 2> 126720 T426 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-193-thread-1]: 0 msec to checkpoint
[junit4] 2> 126721 T426 oasc.SolrCore.initWriters created json:
solr.JSONResponseWriter
[junit4] 2> 126721 T426 oasc.SolrCore.initWriters adding lazy
queryResponseWriter: solr.VelocityResponseWriter
[junit4] 2> 126721 T426 oasc.SolrCore.initWriters created velocity:
solr.VelocityResponseWriter
[junit4] 2> 126721 T426 oasc.SolrCore.initWriters created xslt:
solr.XSLTResponseWriter
[junit4] 2> 126721 T426 oasr.XSLTResponseWriter.init
xsltCacheLifetimeSeconds=5
[junit4] 2> 126723 T426 oasc.SolrCore.loadUpdateProcessorChains no
updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 126724 T426 oasc.RequestHandlers.initHandlersFromConfig
created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 126724 T426 oasc.RequestHandlers.initHandlersFromConfig
created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 126724 T426 oasc.RequestHandlers.initHandlersFromConfig
created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 126724 T426 oasc.RequestHandlers.initHandlersFromConfig
created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 126725 T426 oasc.RequestHandlers.initHandlersFromConfig
created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 126725 T426 oasc.RequestHandlers.initHandlersFromConfig
created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 126725 T426 oasc.RequestHandlers.initHandlersFromConfig
created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 126726 T426 oasc.RequestHandlers.initHandlersFromConfig
created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 126726 T426 oasc.RequestHandlers.initHandlersFromConfig
created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 126726 T426 oasc.RequestHandlers.initHandlersFromConfig
created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 126726 T426 oasc.RequestHandlers.initHandlersFromConfig
created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 126727 T426 oasc.RequestHandlers.initHandlersFromConfig
created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 126727 T426 oasc.RequestHandlers.initHandlersFromConfig
created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 126727 T426 oasc.RequestHandlers.initHandlersFromConfig
created /admin/properties:
org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 126728 T426 oasc.RequestHandlers.initHandlersFromConfig
created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 126728 T426 oasc.RequestHandlers.initHandlersFromConfig
created /admin/file: org.apache.solr.handler.admin.ShowFileRequestHandler
[junit4] 2> 126728 T426 oasc.RequestHandlers.initHandlersFromConfig
created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 126728 T426 oasc.RequestHandlers.initHandlersFromConfig
created /select: solr.SearchHandler
[junit4] 2> 126729 T426 oasc.RequestHandlers.initHandlersFromConfig
created /query: solr.SearchHandler
[junit4] 2> 126729 T426 oasc.RequestHandlers.initHandlersFromConfig
created /export: solr.SearchHandler
[junit4] 2> 126729 T426 oasc.RequestHandlers.initHandlersFromConfig
created /browse: solr.SearchHandler
[junit4] 2> 126729 T426 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.extraction.ExtractingRequestHandler
[junit4] 2> 126729 T426 oasc.RequestHandlers.initHandlersFromConfig
created /update/extract: solr.extraction.ExtractingRequestHandler
[junit4] 2> 126730 T426 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.FieldAnalysisRequestHandler
[junit4] 2> 126730 T426 oasc.RequestHandlers.initHandlersFromConfig
created /analysis/field: solr.FieldAnalysisRequestHandler
[junit4] 2> 126730 T426 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.DocumentAnalysisRequestHandler
[junit4] 2> 126730 T426 oasc.RequestHandlers.initHandlersFromConfig
created /analysis/document: solr.DocumentAnalysisRequestHandler
[junit4] 2> 126730 T426 oasc.RequestHandlers.initHandlersFromConfig
created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 126730 T426 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.SearchHandler
[junit4] 2> 126731 T426 oasc.RequestHandlers.initHandlersFromConfig
created /spell: solr.SearchHandler
[junit4] 2> 126731 T426 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.SearchHandler
[junit4] 2> 126731 T426 oasc.RequestHandlers.initHandlersFromConfig
created /tvrh: solr.SearchHandler
[junit4] 2> 126731 T426 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.SearchHandler
[junit4] 2> 126731 T426 oasc.RequestHandlers.initHandlersFromConfig
created /terms: solr.SearchHandler
[junit4] 2> 126731 T426 oasc.RequestHandlers.initHandlersFromConfig adding
lazy requestHandler: solr.SearchHandler
[junit4] 2> 126731 T426 oasc.RequestHandlers.initHandlersFromConfig
created /elevate: solr.SearchHandler
[junit4] 2> 126735 T426 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 126735 T426 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 126736 T426 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 126737 T426 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 126739 T426 oasu.CommitTracker.<init> Hard AutoCommit: if
uncommited for 15000ms;
[junit4] 2> 126739 T426 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 126739 T426 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-193-thread-1]: init: current segments file is
"segments_1";
deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@e1c482
[junit4] 2> 126739 T426 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-193-thread-1]: init: load commit "segments_1"
[junit4] 2> 126740 T426 oasc.SolrDeletionPolicy.onInit
SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=BaseDirectoryWrapper(RAMDirectory@168b0b
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1dc7623),segFN=segments_1,generation=1}
[junit4] 2> 126740 T426 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 1
[junit4] 2> 126740 T426 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-193-thread-1]: now checkpoint "" [0 segments ; isCommit
= false]
[junit4] 2> 126740 T426 oasu.LoggingInfoStream.message
[IFD][coreLoadExecutor-193-thread-1]: 0 msec to checkpoint
[junit4] 2> 126740 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: init: create=false
[junit4] 2> 126740 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]:
[junit4] 2> dir=BaseDirectoryWrapper(RAMDirectory@168b0b
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1dc7623)
[junit4] 2> index=
[junit4] 2> version=5.0.0
[junit4] 2> analyzer=null
[junit4] 2> ramBufferSizeMB=100.0
[junit4] 2> maxBufferedDocs=-1
[junit4] 2> maxBufferedDeleteTerms=-1
[junit4] 2> mergedSegmentWarmer=null
[junit4] 2>
delPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper
[junit4] 2> commit=null
[junit4] 2> openMode=APPEND
[junit4] 2>
similarity=org.apache.lucene.search.similarities.DefaultSimilarity
[junit4] 2> mergeScheduler=ConcurrentMergeScheduler:
maxThreadCount=1, maxMergeCount=2, mergeThreadPriority=-1
[junit4] 2> default WRITE_LOCK_TIMEOUT=1000
[junit4] 2> writeLockTimeout=1000
[junit4] 2> codec=Lucene50
[junit4] 2> infoStream=org.apache.solr.update.LoggingInfoStream
[junit4] 2> mergePolicy=[TieredMergePolicy: maxMergeAtOnce=10,
maxMergeAtOnceExplicit=30, maxMergedSegmentMB=5120.0, floorSegmentMB=2.0,
forceMergeDeletesPctAllowed=10.0, segmentsPerTier=10.0,
maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2>
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@ec3e1a
[junit4] 2> readerPooling=false
[junit4] 2> perThreadHardLimitMB=1945
[junit4] 2> useCompoundFile=false
[junit4] 2> commitOnClose=true
[junit4] 2> writer=org.apache.lucene.util.SetOnce@12c4bd3
[junit4] 2>
[junit4] 2> 126741 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: flush at getReader
[junit4] 2> 126741 T426 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-193-thread-1]: startFullFlush
[junit4] 2> 126741 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: apply all deletes during flush
[junit4] 2> 126741 T426 oasu.LoggingInfoStream.message
[BD][coreLoadExecutor-193-thread-1]: prune sis=segments_1:
minGen=9223372036854775807 packetCount=0
[junit4] 2> 126741 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: return reader version=1
reader=StandardDirectoryReader(segments_1:1:nrt)
[junit4] 2> 126742 T426 oasu.LoggingInfoStream.message
[DW][coreLoadExecutor-193-thread-1]: coreLoadExecutor-193-thread-1
finishFullFlush success=true
[junit4] 2> 126742 T426 oasu.LoggingInfoStream.message
[IW][coreLoadExecutor-193-thread-1]: getReader took 1 msec
[junit4] 2> 126742 T426 oass.SolrIndexSearcher.<init> Opening
Searcher@1ed6ac1[collection1] main
[junit4] 2> 126742 T426
oasr.ManagedResourceStorage$FileStorageIO.configure File-based storage
initialized to use dir:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/collection1/conf
[junit4] 2> 126742 T426 oasr.RestManager.init Initializing RestManager
with initArgs:
{storageDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/collection1/conf}
[junit4] 2> 126743 T426 oasr.ManagedResourceStorage.load Reading
_rest_managed.json using
file:dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/collection1/conf
[junit4] 2> 126743 T426 oasr.ManagedResourceStorage.load Loaded
LinkedHashMap at path _rest_managed.json using
file:dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/collection1/conf
[junit4] 2> 126743 T426 oasr.ManagedResource.reloadFromStorage Loaded
initArgs {} for /rest/managed
[junit4] 2> 126743 T426 oasr.RestManager.init Initializing 2 registered
ManagedResources
[junit4] 2> 126743 T426 oasr.ManagedResourceStorage.load Reading
_schema_analysis_stopwords_english.json using
file:dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/collection1/conf
[junit4] 2> 126744 T426 oasr.ManagedResourceStorage.load Loaded
LinkedHashMap at path _schema_analysis_stopwords_english.json using
file:dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/collection1/conf
[junit4] 2> 126744 T426 oasr.ManagedResource.reloadFromStorage Loaded
initArgs {ignoreCase=true} for /schema/analysis/stopwords/english
[junit4] 2> 126744 T426
oasrsa.ManagedWordSetResource.onManagedDataLoadedFromStorage Loaded 35 words
for /schema/analysis/stopwords/english
[junit4] 2> 126744 T426 oasr.ManagedResource.notifyObserversDuringInit
Notified 1 observers of /schema/analysis/stopwords/english
[junit4] 2> 126744 T426 oasr.ManagedResourceStorage.load Reading
_schema_analysis_synonyms_english.json using
file:dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/collection1/conf
[junit4] 2> 126744 T426 oasr.ManagedResourceStorage.load Loaded
LinkedHashMap at path _schema_analysis_synonyms_english.json using
file:dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/collection1/conf
[junit4] 2> 126745 T426 oasr.ManagedResource.reloadFromStorage Loaded
initArgs {ignoreCase=true,format=solr} for /schema/analysis/synonyms/english
[junit4] 2> 126745 T426
oasrsa.ManagedSynonymFilterFactory$SynonymManager.onManagedDataLoadedFromStorage
Loaded 3 synonym mappings for /schema/analysis/synonyms/english
[junit4] 2> 126745 T426 oasr.ManagedResource.notifyObserversDuringInit
Notified 1 observers of /schema/analysis/synonyms/english
[junit4] 2> 126745 T426 oashc.SpellCheckComponent.inform Initializing
spell checkers
[junit4] 2> 126745 T426 oass.DirectSolrSpellChecker.init init:
{name=default,field=text,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 126746 T426 oashc.SpellCheckComponent.inform No queryConverter
defined, using default converter
[junit4] 2> 126746 T426 oashc.QueryElevationComponent.inform Loading
QueryElevation from:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-001/collection1/conf/elevate.xml
[junit4] 2> 126749 T426 oash.ReplicationHandler.inform Commits will be
reserved for 10000
[junit4] 2> 126750 T427 oasc.QuerySenderListener.newSearcher
QuerySenderListener sending requests to Searcher@1ed6ac1[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 126751 T426 oasc.CoreContainer.registerCore registering core:
collection1
[junit4] 2> ASYNC NEW_CORE C43 name=collection1
org.apache.solr.core.SolrCore@1d89c16
[junit4] 2> 126751 T427 C43 oasc.SolrCore.execute [collection1]
webapp=null path=null
params={q=static+firstSearcher+warming+in+solrconfig.xml&distrib=false&event=firstSearcher}
hits=0 status=0 QTime=1
[junit4] 2> 126751 T416 oass.SolrDispatchFilter.init
user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0
[junit4] 2> 126752 T416 oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 126751 T427 oasc.QuerySenderListener.newSearcher
QuerySenderListener done.
[junit4] 2> 126752 T427
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index
for spellchecker: default
[junit4] 2> 126752 T427
oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index
for spellchecker: wordbreak
[junit4] 2> 126752 T416 oas.SolrJettyTestBase.createJetty Jetty Assigned
Port#55949
[junit4] 2> 126753 T427 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@1ed6ac1[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 126758 T416 oas.SolrTestCaseJ4.setUp ###Starting
testSetNullUpdates
[junit4] 2> 126763 T424 C43 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2}
{add=[testSetNullUpdates (1487037717036400640)]} 0 3
[junit4] 2> 126765 T420 C43 oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 126766 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: commit: start
[junit4] 2> 126767 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: commit: enter lock
[junit4] 2> 126767 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: commit: now prepare
[junit4] 2> 126767 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: prepareCommit: flush
[junit4] 2> 126768 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: index before flush
[junit4] 2> 126768 T420 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-420]: startFullFlush
[junit4] 2> 126769 T420 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-420]: anyChanges? numDocsInRam=1 deletes=true hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 126769 T420 C43 oasu.LoggingInfoStream.message
[DWFC][qtp31378696-420]: addFlushableState DocumentsWriterPerThread
[pendingDeletes=gen=0, segment=_0, aborted=false, numDocsInRAM=1,
deleteQueue=DWDQ: [ generation: 1 ]]
[junit4] 2> 126769 T420 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-420]: flush postings as segment _0 numDocs=1
[junit4] 2> 126772 T420 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-420]: new segment has 0 deleted docs
[junit4] 2> 126772 T420 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-420]: new segment has no vectors; no norms; no docValues; no
prox; no freqs
[junit4] 2> 126773 T420 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-420]: flushedFiles=[_0_Lucene50_0.doc, _0_Lucene50_0.tim,
_0.fdx, _0_Lucene50_0.tip, _0.fdt, _0.fnm]
[junit4] 2> 126773 T420 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-420]: flushed codec=Lucene50
[junit4] 2> 126773 T420 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-420]: flushed: segment=_0 ramUsed=0.095 MB
newFlushedSize(includes docstores)=0.001 MB docs/MB=799.22
[junit4] 2> 126773 T420 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-420]: publishFlushedSegment seg-private updates=null
[junit4] 2> 126774 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: publishFlushedSegment
[junit4] 2> 126774 T420 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-420]: push deletes 1 deleted terms (unique count=1)
bytesUsed=1056 delGen=2 packetCount=1 totBytesUsed=1056
[junit4] 2> 126775 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: publish sets newSegment delGen=3 seg=_0(5.0.0):C1
[junit4] 2> 126775 T420 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-420]: now checkpoint "_0(5.0.0):C1" [1 segments ; isCommit =
false]
[junit4] 2> 126775 T420 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-420]: 0 msec to checkpoint
[junit4] 2> 126775 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: apply all deletes during flush
[junit4] 2> 126775 T420 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-420]: applyDeletes: infos=[_0(5.0.0):C1] packetCount=1
[junit4] 2> 126776 T420 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-420]: applyDeletes took 1 msec
[junit4] 2> 126776 T420 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-420]: prune sis=segments_1: _0(5.0.0):C1 minGen=4 packetCount=1
[junit4] 2> 126776 T420 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-420]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 126776 T420 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-420]: qtp31378696-420 finishFullFlush success=true
[junit4] 2> 126777 T420 C43 oasu.LoggingInfoStream.message
[TMP][qtp31378696-420]: findMerges: 1 segments
[junit4] 2> 126777 T420 C43 oasu.LoggingInfoStream.message
[TMP][qtp31378696-420]: seg=_0(5.0.0):C1 size=0.001 MB [floored]
[junit4] 2> 126777 T420 C43 oasu.LoggingInfoStream.message
[TMP][qtp31378696-420]: allowedSegmentCount=1 vs count=1 (eligible count=1)
tooBigCount=0
[junit4] 2> 126777 T420 C43 oasu.LoggingInfoStream.message
[CMS][qtp31378696-420]: now merge
[junit4] 2> 126778 T420 C43 oasu.LoggingInfoStream.message
[CMS][qtp31378696-420]: index: _0(5.0.0):C1
[junit4] 2> 126778 T420 C43 oasu.LoggingInfoStream.message
[CMS][qtp31378696-420]: no more merges pending; now return
[junit4] 2> 126778 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: startCommit(): start
[junit4] 2> 126778 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: startCommit index=_0(5.0.0):C1 changeCount=3
[junit4] 2> 126780 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: done all syncs: [_0.si, _0_Lucene50_0.doc,
_0_Lucene50_0.tim, _0.fdx, _0_Lucene50_0.tip, _0.fdt, _0.fnm]
[junit4] 2> 126781 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: commit: pendingCommit != null
[junit4] 2> 126781 T420 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-420]: now checkpoint "_0(5.0.0):C1" [1 segments ; isCommit =
true]
[junit4] 2> 126781 T420 C43 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2>
commit{dir=BaseDirectoryWrapper(RAMDirectory@168b0b
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1dc7623),segFN=segments_1,generation=1}
[junit4] 2>
commit{dir=BaseDirectoryWrapper(RAMDirectory@168b0b
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1dc7623),segFN=segments_2,generation=2}
[junit4] 2> 126782 T420 C43 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 2
[junit4] 2> 126782 T420 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-420]: deleteCommits: now decRef commit "segments_1"
[junit4] 2> 126782 T420 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-420]: delete "segments_1"
[junit4] 2> 126783 T420 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-420]: 1 msec to checkpoint
[junit4] 2> 126783 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: commit: wrote segments file "segments_2"
[junit4] 2> 126784 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: commit: took 16.2 msec
[junit4] 2> 126784 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: commit: done
[junit4] 2> 126785 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: nrtIsCurrent: infoVersion matches: false; DW changes:
false; BD changes: false
[junit4] 2> 126785 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: flush at getReader
[junit4] 2> 126785 T420 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-420]: startFullFlush
[junit4] 2> 126785 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: apply all deletes during flush
[junit4] 2> 126786 T420 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-420]: applyDeletes: no deletes; skipping
[junit4] 2> 126786 T420 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-420]: prune sis=segments_1: _0(5.0.0):C1 minGen=4 packetCount=0
[junit4] 2> 126786 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: return reader version=3
reader=StandardDirectoryReader(segments_1:3:nrt _0(5.0.0):C1)
[junit4] 2> 126787 T420 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-420]: qtp31378696-420 finishFullFlush success=true
[junit4] 2> 126787 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: getReader took 2 msec
[junit4] 2> 126787 T420 C43 oass.SolrIndexSearcher.<init> Opening
Searcher@1d90105[collection1] main
[junit4] 2> 126788 T427 oasc.QuerySenderListener.newSearcher
QuerySenderListener sending requests to Searcher@1d90105[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):C1)))}
[junit4] 2> 126788 T420 C43 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4] 2> 126788 T427 oasc.QuerySenderListener.newSearcher
QuerySenderListener done.
[junit4] 2> 126790 T427 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@1d90105[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.0.0):C1)))}
[junit4] 2> 126790 T420 C43 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
{commit=} 0 25
[junit4] 2> 126794 T425 C43 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2}
{add=[testSetNullUpdates (1487037717069955072)]} 0 2
[junit4] 2> 126796 T423 C43 oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 126797 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: commit: start
[junit4] 2> 126797 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: commit: enter lock
[junit4] 2> 126797 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: commit: now prepare
[junit4] 2> 126797 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: prepareCommit: flush
[junit4] 2> 126798 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: index before flush _0(5.0.0):C1
[junit4] 2> 126798 T423 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-423]: startFullFlush
[junit4] 2> 126798 T423 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-423]: anyChanges? numDocsInRam=1 deletes=true hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 126798 T423 C43 oasu.LoggingInfoStream.message
[DWFC][qtp31378696-423]: addFlushableState DocumentsWriterPerThread
[pendingDeletes=gen=0, segment=_1, aborted=false, numDocsInRAM=1,
deleteQueue=DWDQ: [ generation: 3 ]]
[junit4] 2> 126799 T423 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-423]: flush postings as segment _1 numDocs=1
[junit4] 2> 126799 T423 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-423]: new segment has 0 deleted docs
[junit4] 2> 126799 T423 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-423]: new segment has no vectors; no norms; no docValues; no
prox; no freqs
[junit4] 2> 126799 T423 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-423]: flushedFiles=[_1_Lucene50_0.doc, _1_Lucene50_0.tim,
_1.fdx, _1.fnm, _1.fdt, _1_Lucene50_0.tip]
[junit4] 2> 126800 T423 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-423]: flushed codec=Lucene50
[junit4] 2> 126800 T423 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-423]: flushed: segment=_1 ramUsed=0.087 MB
newFlushedSize(includes docstores)=0.001 MB docs/MB=927.943
[junit4] 2> 126800 T423 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-423]: publishFlushedSegment seg-private updates=null
[junit4] 2> 126800 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: publishFlushedSegment
[junit4] 2> 126801 T423 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-423]: push deletes 1 deleted terms (unique count=1)
bytesUsed=1056 delGen=6 packetCount=1 totBytesUsed=1056
[junit4] 2> 126801 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: publish sets newSegment delGen=7 seg=_1(5.0.0):C1
[junit4] 2> 126801 T423 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-423]: now checkpoint "_0(5.0.0):C1 _1(5.0.0):C1" [2 segments
; isCommit = false]
[junit4] 2> 126801 T423 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-423]: 0 msec to checkpoint
[junit4] 2> 126802 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: apply all deletes during flush
[junit4] 2> 126802 T423 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-423]: applyDeletes: infos=[_0(5.0.0):C1, _1(5.0.0):C1]
packetCount=1
[junit4] 2> 126802 T423 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-423]: seg=_0(5.0.0):C1 segGen=4 coalesced
deletes=[CoalescedUpdates(termSets=1,queries=0,numericDVUpdates=0,binaryDVUpdates=0)]
newDelCount=1 100% deleted
[junit4] 2> 126802 T423 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-423]: applyDeletes took 0 msec
[junit4] 2> 126803 T423 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-423]: now checkpoint "_0(5.0.0):C1/1 _1(5.0.0):C1" [2
segments ; isCommit = false]
[junit4] 2> 126803 T423 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-423]: 0 msec to checkpoint
[junit4] 2> 126803 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: drop 100% deleted segments: _0(5.0.0):C1/1
[junit4] 2> 126803 T423 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-423]: now checkpoint "_1(5.0.0):C1" [1 segments ; isCommit =
false]
[junit4] 2> 126804 T423 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-423]: 0 msec to checkpoint
[junit4] 2> 126804 T423 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-423]: prune sis=segments_1: _1(5.0.0):C1 minGen=8 packetCount=1
[junit4] 2> 126804 T423 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-423]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 126804 T423 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-423]: qtp31378696-423 finishFullFlush success=true
[junit4] 2> 126804 T423 C43 oasu.LoggingInfoStream.message
[TMP][qtp31378696-423]: findMerges: 1 segments
[junit4] 2> 126805 T423 C43 oasu.LoggingInfoStream.message
[TMP][qtp31378696-423]: seg=_1(5.0.0):C1 size=0.001 MB [floored]
[junit4] 2> 126805 T423 C43 oasu.LoggingInfoStream.message
[TMP][qtp31378696-423]: allowedSegmentCount=1 vs count=1 (eligible count=1)
tooBigCount=0
[junit4] 2> 126805 T423 C43 oasu.LoggingInfoStream.message
[CMS][qtp31378696-423]: now merge
[junit4] 2> 126806 T423 C43 oasu.LoggingInfoStream.message
[CMS][qtp31378696-423]: index: _1(5.0.0):C1
[junit4] 2> 126806 T423 C43 oasu.LoggingInfoStream.message
[CMS][qtp31378696-423]: no more merges pending; now return
[junit4] 2> 126806 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: startCommit(): start
[junit4] 2> 126806 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: startCommit index=_1(5.0.0):C1 changeCount=8
[junit4] 2> 126806 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: done all syncs: [_1_Lucene50_0.doc, _1_Lucene50_0.tim,
_1.si, _1.fdx, _1.fnm, _1.fdt, _1_Lucene50_0.tip]
[junit4] 2> 126807 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: commit: pendingCommit != null
[junit4] 2> 126807 T423 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-423]: now checkpoint "_1(5.0.0):C1" [1 segments ; isCommit =
true]
[junit4] 2> 126807 T423 C43 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2>
commit{dir=BaseDirectoryWrapper(RAMDirectory@168b0b
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1dc7623),segFN=segments_2,generation=2}
[junit4] 2>
commit{dir=BaseDirectoryWrapper(RAMDirectory@168b0b
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1dc7623),segFN=segments_3,generation=3}
[junit4] 2> 126807 T423 C43 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 3
[junit4] 2> 126808 T423 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-423]: deleteCommits: now decRef commit "segments_2"
[junit4] 2> 126808 T423 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-423]: delete "segments_2"
[junit4] 2> 126808 T423 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-423]: 1 msec to checkpoint
[junit4] 2> 126808 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: commit: wrote segments file "segments_3"
[junit4] 2> 126809 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: commit: took 11.2 msec
[junit4] 2> 126809 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: commit: done
[junit4] 2> 126809 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: nrtIsCurrent: infoVersion matches: false; DW changes:
false; BD changes: false
[junit4] 2> 126809 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: flush at getReader
[junit4] 2> 126809 T423 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-423]: startFullFlush
[junit4] 2> 126810 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: apply all deletes during flush
[junit4] 2> 126810 T423 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-423]: applyDeletes: no deletes; skipping
[junit4] 2> 126810 T423 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-423]: prune sis=segments_1: _1(5.0.0):C1 minGen=8 packetCount=0
[junit4] 2> 126810 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: return reader version=7
reader=StandardDirectoryReader(segments_1:7:nrt _1(5.0.0):C1)
[junit4] 2> 126811 T423 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-423]: qtp31378696-423 finishFullFlush success=true
[junit4] 2> 126811 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: getReader took 2 msec
[junit4] 2> 126811 T423 C43 oass.SolrIndexSearcher.<init> Opening
Searcher@c21d6e[collection1] main
[junit4] 2> 126812 T427 oasc.QuerySenderListener.newSearcher
QuerySenderListener sending requests to Searcher@c21d6e[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.0.0):C1)))}
[junit4] 2> 126812 T423 C43 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4] 2> 126812 T427 oasc.QuerySenderListener.newSearcher
QuerySenderListener done.
[junit4] 2> 126813 T427 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@c21d6e[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.0.0):C1)))}
[junit4] 2> 126813 T427 oasu.LoggingInfoStream.message
[IFD][searcherExecutor-194-thread-1]: delete "_0.si"
[junit4] 2> 126813 T427 oasu.LoggingInfoStream.message
[IFD][searcherExecutor-194-thread-1]: delete "_0_Lucene50_0.doc"
[junit4] 2> 126814 T427 oasu.LoggingInfoStream.message
[IFD][searcherExecutor-194-thread-1]: delete "_0_Lucene50_0.tim"
[junit4] 2> 126814 T427 oasu.LoggingInfoStream.message
[IFD][searcherExecutor-194-thread-1]: delete "_0.fdx"
[junit4] 2> 126814 T427 oasu.LoggingInfoStream.message
[IFD][searcherExecutor-194-thread-1]: delete "_0_Lucene50_0.tip"
[junit4] 2> 126814 T427 oasu.LoggingInfoStream.message
[IFD][searcherExecutor-194-thread-1]: delete "_0.fdt"
[junit4] 2> 126814 T427 oasu.LoggingInfoStream.message
[IFD][searcherExecutor-194-thread-1]: delete "_0.fnm"
[junit4] 2> 126814 T423 C43 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
{commit=} 0 18
[junit4] 2> 126817 T424 C43 oasc.SolrCore.execute [collection1]
webapp=/solr path=/select params={q=id:testSetNullUpdates&wt=javabin&version=2}
hits=1 status=0 QTime=1
[junit4] 2> 126818 T416 oas.SolrTestCaseJ4.tearDown ###Ending
testSetNullUpdates
[junit4] 2> 126823 T416 oas.SolrTestCaseJ4.setUp ###Starting
testLukeHandler
[junit4] 2> 126826 T420 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-420]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 126826 T420 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-420]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 126827 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: nrtIsCurrent: infoVersion matches: true; DW changes:
true; BD changes: false
[junit4] 2> 126827 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: flush at getReader
[junit4] 2> 126827 T420 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-420]: startFullFlush
[junit4] 2> 126827 T420 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-420]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 126828 T420 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-420]: qtp31378696-420: flush naked frozen global deletes
[junit4] 2> 126828 T420 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-420]: push deletes 1 deleted queries bytesUsed=64 delGen=10
packetCount=1 totBytesUsed=64
[junit4] 2> 126828 T420 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-420]: flush: push buffered updates: 1 deleted queries
bytesUsed=64
[junit4] 2> 126828 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: apply all deletes during flush
[junit4] 2> 126829 T420 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-420]: applyDeletes: infos=[_1(5.0.0):C1] packetCount=1
[junit4] 2> 126829 T420 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-420]: seg=_1(5.0.0):C1 segGen=8 coalesced
deletes=[CoalescedUpdates(termSets=1,queries=1,numericDVUpdates=0,binaryDVUpdates=0)]
newDelCount=1 100% deleted
[junit4] 2> 126829 T420 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-420]: applyDeletes took 0 msec
[junit4] 2> 126830 T420 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-420]: now checkpoint "_1(5.0.0):C1/1" [1 segments ; isCommit
= false]
[junit4] 2> 126830 T420 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-420]: 0 msec to checkpoint
[junit4] 2> 126830 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: drop 100% deleted segments: _1(5.0.0):C1/1
[junit4] 2> 126830 T420 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-420]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 126830 T420 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-420]: 0 msec to checkpoint
[junit4] 2> 126831 T420 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-420]: prune sis=segments_1: minGen=9223372036854775807
packetCount=1
[junit4] 2> 126831 T420 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-420]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 126831 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: return reader version=9
reader=StandardDirectoryReader(segments_1:9:nrt)
[junit4] 2> 126831 T420 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-420]: qtp31378696-420 finishFullFlush success=true
[junit4] 2> 126832 T420 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-420]: getReader took 5 msec
[junit4] 2> 126832 T420 C43 oass.SolrIndexSearcher.<init> Opening
Searcher@8a554f[collection1] realtime
[junit4] 2> 126832 T420 C43 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*
(-1487037717103509504)} 0 7
[junit4] 2> 126835 T424 C43 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID0
(1487037717112946688)]} 0 1
[junit4] 2> 126837 T425 C43 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID1
(1487037717116092416)]} 0 0
[junit4] 2> 126839 T420 C43 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID2
(1487037717118189568)]} 0 0
[junit4] 2> 126841 T423 C43 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID3
(1487037717120286720)]} 0 0
[junit4] 2> 126843 T424 C43 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[ID4
(1487037717122383872)]} 0 0
[junit4] 2> 126845 T425 C43 oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 126845 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: commit: start
[junit4] 2> 126846 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: commit: enter lock
[junit4] 2> 126846 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: commit: now prepare
[junit4] 2> 126846 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: prepareCommit: flush
[junit4] 2> 126846 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: index before flush
[junit4] 2> 126846 T425 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-425]: startFullFlush
[junit4] 2> 126847 T425 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-425]: anyChanges? numDocsInRam=5 deletes=true hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 126847 T425 C43 oasu.LoggingInfoStream.message
[DWFC][qtp31378696-425]: addFlushableState DocumentsWriterPerThread
[pendingDeletes=gen=0 4 deleted terms (unique count=4) bytesUsed=556,
segment=_2, aborted=false, numDocsInRAM=5, deleteQueue=DWDQ: [ generation: 6 ]]
[junit4] 2> 126847 T425 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-425]: flush postings as segment _2 numDocs=5
[junit4] 2> 126848 T425 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-425]: new segment has 0 deleted docs
[junit4] 2> 126848 T425 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-425]: new segment has no vectors; no norms; no docValues; no
prox; no freqs
[junit4] 2> 126848 T425 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-425]: flushedFiles=[_2.fdt, _2.fnm, _2.fdx,
_2_Lucene50_0.doc, _2_Lucene50_0.tim, _2_Lucene50_0.tip]
[junit4] 2> 126848 T425 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-425]: flushed codec=Lucene50
[junit4] 2> 126849 T425 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-425]: flushed: segment=_2 ramUsed=0.08 MB
newFlushedSize(includes docstores)=0.001 MB docs/MB=5,170.493
[junit4] 2> 126849 T425 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-425]: publishFlushedSegment seg-private updates=null
[junit4] 2> 126849 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: publishFlushedSegment
[junit4] 2> 126850 T425 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-425]: push deletes 5 deleted terms (unique count=5)
bytesUsed=1056 delGen=12 packetCount=1 totBytesUsed=1056
[junit4] 2> 126850 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: publish sets newSegment delGen=13 seg=_2(5.0.0):C5
[junit4] 2> 126850 T425 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-425]: now checkpoint "_2(5.0.0):C5" [1 segments ; isCommit =
false]
[junit4] 2> 126850 T425 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-425]: 0 msec to checkpoint
[junit4] 2> 126851 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: apply all deletes during flush
[junit4] 2> 126851 T425 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-425]: applyDeletes: infos=[_2(5.0.0):C5] packetCount=1
[junit4] 2> 126851 T425 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-425]: applyDeletes took 0 msec
[junit4] 2> 126851 T425 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-425]: prune sis=segments_1: _2(5.0.0):C5 minGen=14
packetCount=1
[junit4] 2> 126851 T425 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-425]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 126852 T425 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-425]: qtp31378696-425 finishFullFlush success=true
[junit4] 2> 126852 T425 C43 oasu.LoggingInfoStream.message
[TMP][qtp31378696-425]: findMerges: 1 segments
[junit4] 2> 126852 T425 C43 oasu.LoggingInfoStream.message
[TMP][qtp31378696-425]: seg=_2(5.0.0):C5 size=0.001 MB [floored]
[junit4] 2> 126852 T425 C43 oasu.LoggingInfoStream.message
[TMP][qtp31378696-425]: allowedSegmentCount=1 vs count=1 (eligible count=1)
tooBigCount=0
[junit4] 2> 126853 T425 C43 oasu.LoggingInfoStream.message
[CMS][qtp31378696-425]: now merge
[junit4] 2> 126853 T425 C43 oasu.LoggingInfoStream.message
[CMS][qtp31378696-425]: index: _2(5.0.0):C5
[junit4] 2> 126853 T425 C43 oasu.LoggingInfoStream.message
[CMS][qtp31378696-425]: no more merges pending; now return
[junit4] 2> 126853 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: startCommit(): start
[junit4] 2> 126854 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: startCommit index=_2(5.0.0):C5 changeCount=13
[junit4] 2> 126854 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: done all syncs: [_2.si, _2.fdt, _2.fnm, _2.fdx,
_2_Lucene50_0.doc, _2_Lucene50_0.tim, _2_Lucene50_0.tip]
[junit4] 2> 126854 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: commit: pendingCommit != null
[junit4] 2> 126854 T425 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-425]: now checkpoint "_2(5.0.0):C5" [1 segments ; isCommit =
true]
[junit4] 2> 126855 T425 C43 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2>
commit{dir=BaseDirectoryWrapper(RAMDirectory@168b0b
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1dc7623),segFN=segments_3,generation=3}
[junit4] 2>
commit{dir=BaseDirectoryWrapper(RAMDirectory@168b0b
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1dc7623),segFN=segments_4,generation=4}
[junit4] 2> 126855 T425 C43 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 4
[junit4] 2> 126855 T425 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-425]: deleteCommits: now decRef commit "segments_3"
[junit4] 2> 126855 T425 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-425]: delete "segments_3"
[junit4] 2> 126856 T425 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-425]: 1 msec to checkpoint
[junit4] 2> 126856 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: commit: wrote segments file "segments_4"
[junit4] 2> 126856 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: commit: took 10.1 msec
[junit4] 2> 126856 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: commit: done
[junit4] 2> 126857 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: nrtIsCurrent: infoVersion matches: false; DW changes:
false; BD changes: false
[junit4] 2> 126857 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: flush at getReader
[junit4] 2> 126857 T425 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-425]: startFullFlush
[junit4] 2> 126857 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: apply all deletes during flush
[junit4] 2> 126858 T425 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-425]: applyDeletes: no deletes; skipping
[junit4] 2> 126858 T425 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-425]: prune sis=segments_1: _2(5.0.0):C5 minGen=14
packetCount=0
[junit4] 2> 126858 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: return reader version=11
reader=StandardDirectoryReader(segments_1:11:nrt _2(5.0.0):C5)
[junit4] 2> 126858 T425 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-425]: qtp31378696-425 finishFullFlush success=true
[junit4] 2> 126859 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: getReader took 2 msec
[junit4] 2> 126859 T425 C43 oass.SolrIndexSearcher.<init> Opening
Searcher@1c1ca09[collection1] main
[junit4] 2> 126859 T425 C43 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4] 2> 126859 T427 oasc.QuerySenderListener.newSearcher
QuerySenderListener sending requests to Searcher@1c1ca09[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(5.0.0):C5)))}
[junit4] 2> 126860 T427 oasc.QuerySenderListener.newSearcher
QuerySenderListener done.
[junit4] 2> 126861 T427 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@1c1ca09[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(5.0.0):C5)))}
[junit4] 2> 126861 T427 oasu.LoggingInfoStream.message
[IFD][searcherExecutor-194-thread-1]: delete "_1_Lucene50_0.doc"
[junit4] 2> 126861 T427 oasu.LoggingInfoStream.message
[IFD][searcherExecutor-194-thread-1]: delete "_1_Lucene50_0.tim"
[junit4] 2> 126861 T427 oasu.LoggingInfoStream.message
[IFD][searcherExecutor-194-thread-1]: delete "_1.si"
[junit4] 2> 126861 T427 oasu.LoggingInfoStream.message
[IFD][searcherExecutor-194-thread-1]: delete "_1.fdx"
[junit4] 2> 126862 T427 oasu.LoggingInfoStream.message
[IFD][searcherExecutor-194-thread-1]: delete "_1.fnm"
[junit4] 2> 126862 T427 oasu.LoggingInfoStream.message
[IFD][searcherExecutor-194-thread-1]: delete "_1.fdt"
[junit4] 2> 126862 T427 oasu.LoggingInfoStream.message
[IFD][searcherExecutor-194-thread-1]: delete "_1_Lucene50_0.tip"
[junit4] 2> 126862 T425 C43 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
{commit=} 0 17
[junit4] 2> 126865 T420 C43 oasc.SolrCore.execute [collection1]
webapp=/solr path=/select params={q=*:*&wt=javabin&version=2} hits=5 status=0
QTime=1
[junit4] 2> 126867 T423 C43 oasc.SolrCore.execute [collection1]
webapp=/solr path=/admin/luke params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 126872 T424 C43 oasc.SolrCore.execute [collection1]
webapp=/solr path=/admin/luke params={show=schema&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 126875 T416 oas.SolrTestCaseJ4.tearDown ###Ending
testLukeHandler
[junit4] 2> 126884 T416 oas.SolrTestCaseJ4.setUp ###Starting
testExampleConfig
[junit4] 2> 126887 T425 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-425]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 126888 T425 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-425]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 126888 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: nrtIsCurrent: infoVersion matches: true; DW changes:
true; BD changes: false
[junit4] 2> 126889 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: flush at getReader
[junit4] 2> 126889 T425 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-425]: startFullFlush
[junit4] 2> 126890 T425 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-425]: anyChanges? numDocsInRam=0 deletes=true hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 126890 T425 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-425]: qtp31378696-425: flush naked frozen global deletes
[junit4] 2> 126891 T425 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-425]: push deletes 1 deleted queries bytesUsed=64 delGen=16
packetCount=1 totBytesUsed=64
[junit4] 2> 126891 T425 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-425]: flush: push buffered updates: 1 deleted queries
bytesUsed=64
[junit4] 2> 126892 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: apply all deletes during flush
[junit4] 2> 126892 T425 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-425]: applyDeletes: infos=[_2(5.0.0):C5] packetCount=1
[junit4] 2> 126893 T425 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-425]: seg=_2(5.0.0):C5 segGen=14 coalesced
deletes=[CoalescedUpdates(termSets=1,queries=1,numericDVUpdates=0,binaryDVUpdates=0)]
newDelCount=5 100% deleted
[junit4] 2> 126894 T425 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-425]: applyDeletes took 2 msec
[junit4] 2> 126894 T425 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-425]: now checkpoint "_2(5.0.0):C5/5" [1 segments ; isCommit
= false]
[junit4] 2> 126895 T425 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-425]: 0 msec to checkpoint
[junit4] 2> 126895 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: drop 100% deleted segments: _2(5.0.0):C5/5
[junit4] 2> 126896 T425 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-425]: now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 126896 T425 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-425]: 0 msec to checkpoint
[junit4] 2> 126897 T425 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-425]: prune sis=segments_1: minGen=9223372036854775807
packetCount=1
[junit4] 2> 126897 T425 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-425]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 126898 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: return reader version=13
reader=StandardDirectoryReader(segments_1:13:nrt)
[junit4] 2> 126898 T425 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-425]: qtp31378696-425 finishFullFlush success=true
[junit4] 2> 126899 T425 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-425]: getReader took 10 msec
[junit4] 2> 126899 T425 C43 oass.SolrIndexSearcher.<init> Opening
Searcher@11cc085[collection1] realtime
[junit4] 2> 126900 T425 C43 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {deleteByQuery=*:*
(-1487037717167472640)} 0 14
[junit4] 2> 126903 T420 C43 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[1112211111
(1487037717184249856)]} 0 1
[junit4] 2> 126906 T423 C43 oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 126907 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: commit: start
[junit4] 2> 126907 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: commit: enter lock
[junit4] 2> 126908 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: commit: now prepare
[junit4] 2> 126908 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: prepareCommit: flush
[junit4] 2> 126909 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: index before flush
[junit4] 2> 126909 T423 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-423]: startFullFlush
[junit4] 2> 126910 T423 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-423]: anyChanges? numDocsInRam=1 deletes=true hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 126910 T423 C43 oasu.LoggingInfoStream.message
[DWFC][qtp31378696-423]: addFlushableState DocumentsWriterPerThread
[pendingDeletes=gen=0, segment=_3, aborted=false, numDocsInRAM=1,
deleteQueue=DWDQ: [ generation: 9 ]]
[junit4] 2> 126911 T423 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-423]: flush postings as segment _3 numDocs=1
[junit4] 2> 126912 T423 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-423]: new segment has 0 deleted docs
[junit4] 2> 126913 T423 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-423]: new segment has no vectors; norms; no docValues; prox;
freqs
[junit4] 2> 126913 T423 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-423]: flushedFiles=[_3.fdx, _3.nvd, _3_Lucene50_0.pos,
_3_Lucene50_0.doc, _3_Lucene50_0.tim, _3.nvm, _3.fnm, _3.fdt, _3_Lucene50_0.tip]
[junit4] 2> 126914 T423 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-423]: flushed codec=Lucene50
[junit4] 2> 126914 T423 C43 oasu.LoggingInfoStream.message
[DWPT][qtp31378696-423]: flushed: segment=_3 ramUsed=0.095 MB
newFlushedSize(includes docstores)=0.001 MB docs/MB=713.317
[junit4] 2> 126915 T423 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-423]: publishFlushedSegment seg-private updates=null
[junit4] 2> 126915 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: publishFlushedSegment
[junit4] 2> 126916 T423 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-423]: push deletes 1 deleted terms (unique count=1)
bytesUsed=1056 delGen=18 packetCount=1 totBytesUsed=1056
[junit4] 2> 126916 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: publish sets newSegment delGen=19 seg=_3(5.0.0):C1
[junit4] 2> 126917 T423 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-423]: now checkpoint "_3(5.0.0):C1" [1 segments ; isCommit =
false]
[junit4] 2> 126917 T423 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-423]: 0 msec to checkpoint
[junit4] 2> 126918 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: apply all deletes during flush
[junit4] 2> 126918 T423 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-423]: applyDeletes: infos=[_3(5.0.0):C1] packetCount=1
[junit4] 2> 126918 T423 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-423]: applyDeletes took 0 msec
[junit4] 2> 126918 T423 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-423]: prune sis=segments_1: _3(5.0.0):C1 minGen=20
packetCount=1
[junit4] 2> 126919 T423 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-423]: pruneDeletes: prune 1 packets; 0 packets remain
[junit4] 2> 126919 T423 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-423]: qtp31378696-423 finishFullFlush success=true
[junit4] 2> 126919 T423 C43 oasu.LoggingInfoStream.message
[TMP][qtp31378696-423]: findMerges: 1 segments
[junit4] 2> 126920 T423 C43 oasu.LoggingInfoStream.message
[TMP][qtp31378696-423]: seg=_3(5.0.0):C1 size=0.001 MB [floored]
[junit4] 2> 126920 T423 C43 oasu.LoggingInfoStream.message
[TMP][qtp31378696-423]: allowedSegmentCount=1 vs count=1 (eligible count=1)
tooBigCount=0
[junit4] 2> 126920 T423 C43 oasu.LoggingInfoStream.message
[CMS][qtp31378696-423]: now merge
[junit4] 2> 126921 T423 C43 oasu.LoggingInfoStream.message
[CMS][qtp31378696-423]: index: _3(5.0.0):C1
[junit4] 2> 126921 T423 C43 oasu.LoggingInfoStream.message
[CMS][qtp31378696-423]: no more merges pending; now return
[junit4] 2> 126921 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: startCommit(): start
[junit4] 2> 126921 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: startCommit index=_3(5.0.0):C1 changeCount=18
[junit4] 2> 126921 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: done all syncs: [_3.fdx, _3.si, _3.nvd,
_3_Lucene50_0.pos, _3_Lucene50_0.doc, _3_Lucene50_0.tim, _3.nvm, _3.fnm,
_3.fdt, _3_Lucene50_0.tip]
[junit4] 2> 126922 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: commit: pendingCommit != null
[junit4] 2> 126922 T423 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-423]: now checkpoint "_3(5.0.0):C1" [1 segments ; isCommit =
true]
[junit4] 2> 126922 T423 C43 oasc.SolrDeletionPolicy.onCommit
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2>
commit{dir=BaseDirectoryWrapper(RAMDirectory@168b0b
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1dc7623),segFN=segments_4,generation=4}
[junit4] 2>
commit{dir=BaseDirectoryWrapper(RAMDirectory@168b0b
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1dc7623),segFN=segments_5,generation=5}
[junit4] 2> 126922 T423 C43 oasc.SolrDeletionPolicy.updateCommits newest
commit generation = 5
[junit4] 2> 126923 T423 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-423]: deleteCommits: now decRef commit "segments_4"
[junit4] 2> 126923 T423 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-423]: delete "segments_4"
[junit4] 2> 126923 T423 C43 oasu.LoggingInfoStream.message
[IFD][qtp31378696-423]: 1 msec to checkpoint
[junit4] 2> 126923 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: commit: wrote segments file "segments_5"
[junit4] 2> 126924 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: commit: took 15.3 msec
[junit4] 2> 126924 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: commit: done
[junit4] 2> 126924 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: nrtIsCurrent: infoVersion matches: false; DW changes:
false; BD changes: false
[junit4] 2> 126924 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: flush at getReader
[junit4] 2> 126925 T423 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-423]: startFullFlush
[junit4] 2> 126925 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: apply all deletes during flush
[junit4] 2> 126925 T423 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-423]: applyDeletes: no deletes; skipping
[junit4] 2> 126925 T423 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-423]: prune sis=segments_1: _3(5.0.0):C1 minGen=20
packetCount=0
[junit4] 2> 126926 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: return reader version=15
reader=StandardDirectoryReader(segments_1:15:nrt _3(5.0.0):C1)
[junit4] 2> 126927 T423 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-423]: qtp31378696-423 finishFullFlush success=true
[junit4] 2> 126927 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: getReader took 3 msec
[junit4] 2> 126927 T423 C43 oass.SolrIndexSearcher.<init> Opening
Searcher@bd992[collection1] main
[junit4] 2> 126928 T423 C43 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4] 2> 126928 T427 oasc.QuerySenderListener.newSearcher
QuerySenderListener sending requests to Searcher@bd992[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(5.0.0):C1)))}
[junit4] 2> 126928 T427 oasc.QuerySenderListener.newSearcher
QuerySenderListener done.
[junit4] 2> 126930 T427 oasc.SolrCore.registerSearcher [collection1]
Registered new searcher Searcher@bd992[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(5.0.0):C1)))}
[junit4] 2> 126931 T427 oasu.LoggingInfoStream.message
[IFD][searcherExecutor-194-thread-1]: delete "_2.si"
[junit4] 2> 126931 T427 oasu.LoggingInfoStream.message
[IFD][searcherExecutor-194-thread-1]: delete "_2.fdt"
[junit4] 2> 126932 T427 oasu.LoggingInfoStream.message
[IFD][searcherExecutor-194-thread-1]: delete "_2.fnm"
[junit4] 2> 126932 T427 oasu.LoggingInfoStream.message
[IFD][searcherExecutor-194-thread-1]: delete "_2.fdx"
[junit4] 2> 126932 T427 oasu.LoggingInfoStream.message
[IFD][searcherExecutor-194-thread-1]: delete "_2_Lucene50_0.doc"
[junit4] 2> 126932 T427 oasu.LoggingInfoStream.message
[IFD][searcherExecutor-194-thread-1]: delete "_2_Lucene50_0.tim"
[junit4] 2> 126932 T427 oasu.LoggingInfoStream.message
[IFD][searcherExecutor-194-thread-1]: delete "_2_Lucene50_0.tip"
[junit4] 2> 126933 T423 C43 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}
{commit=} 0 27
[junit4] 2> 126935 T424 C43 oasu.DirectUpdateHandler2.commit start
commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 126936 T424 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-424]: forceMerge: index now _3(5.0.0):C1
[junit4] 2> 126936 T424 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-424]: now flush at forceMerge
[junit4] 2> 126937 T424 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-424]: start flush: applyAllDeletes=true
[junit4] 2> 126937 T424 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-424]: index before flush _3(5.0.0):C1
[junit4] 2> 126937 T424 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-424]: startFullFlush
[junit4] 2> 126937 T424 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-424]: qtp31378696-424 finishFullFlush success=true
[junit4] 2> 126937 T424 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-424]: apply all deletes during flush
[junit4] 2> 126938 T424 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-424]: applyDeletes: no deletes; skipping
[junit4] 2> 126938 T424 C43 oasu.LoggingInfoStream.message
[BD][qtp31378696-424]: prune sis=segments_1: _3(5.0.0):C1 minGen=20
packetCount=0
[junit4] 2> 126938 T424 C43 oasu.LoggingInfoStream.message
[TMP][qtp31378696-424]: findForcedMerges maxSegmentCount=1 infos=_3(5.0.0):C1
segmentsToMerge={_3(5.0.0):C1=true}
[junit4] 2> 126938 T424 C43 oasu.LoggingInfoStream.message
[TMP][qtp31378696-424]: already merged
[junit4] 2> 126939 T424 C43 oasu.LoggingInfoStream.message
[CMS][qtp31378696-424]: now merge
[junit4] 2> 126939 T424 C43 oasu.LoggingInfoStream.message
[CMS][qtp31378696-424]: index: _3(5.0.0):C1
[junit4] 2> 126939 T424 C43 oasu.LoggingInfoStream.message
[CMS][qtp31378696-424]: no more merges pending; now return
[junit4] 2> 126939 T424 C43 oasu.DirectUpdateHandler2.commit No
uncommitted changes. Skipping IW.commit.
[junit4] 2> 126940 T424 C43 oasc.SolrCore.openNewSearcher
SolrIndexSearcher has not changed - not re-opening:
org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 126941 T424 C43 oasu.DirectUpdateHandler2.commit
end_commit_flush
[junit4] 2> 126941 T424 C43 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update
params={optimize=true&maxSegments=1&waitSearcher=true&wt=javabin&version=2}
{optimize=} 0 6
[junit4] 2> 126944 T425 C43 oasc.SolrCore.execute [collection1]
webapp=/solr path=/select params={q=id:1112211111&wt=javabin&version=2} hits=1
status=0 QTime=1
[junit4] 2> 126949 T420 C43 oasup.LogUpdateProcessor.finish [collection1]
webapp=/solr path=/update params={wt=javabin&version=2} {add=[2
(1487037717231435776), 3 (1487037717232484352), 4 (1487037717233532928), 5
(1487037717233532929)]} 0 2
[junit4] 2> 126951 T423 C43 oasu.DirectUpdateHandler2.commit start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 126952 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: commit: start
[junit4] 2> 126952 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: commit: enter lock
[junit4] 2> 126952 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: commit: now prepare
[junit4] 2> 126953 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: prepareCommit: flush
[junit4] 2> 126953 T423 C43 oasu.LoggingInfoStream.message
[IW][qtp31378696-423]: index before flush _3(5.0.0):C1
[junit4] 2> 126953 T423 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-423]: startFullFlush
[junit4] 2> 126954 T423 C43 oasu.LoggingInfoStream.message
[DW][qtp31378696-423]: anyChanges? numDocsInRam=4 deletes=true hasTickets:false
pendingChangesInFullFlush: false
[junit4] 2> 126954 T423 C43 oasu.LoggingInfoStream.message
[DWFC][qtp31378696-423]: addFlushableState DocumentsWriterPerThread [pend
[...truncated too long message...]
2> at
org.apache.solr.search.SolrIndexSearcher.getStatistics(SolrIndexSearcher.java:2240)
[junit4] 2> at
org.apache.solr.core.JmxMonitoredMap$SolrDynamicMBean.getMBeanInfo(JmxMonitoredMap.java:257)
[junit4] 2> at
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getClassName(DefaultMBeanServerInterceptor.java:1804)
[junit4] 2> at
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1595)
[junit4] 2> at
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1813)
[junit4] 2> at
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:430)
[junit4] 2> at
com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415)
[junit4] 2> at
com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546)
[junit4] 2> at
org.apache.solr.core.JmxMonitoredMap.clear(JmxMonitoredMap.java:124)
[junit4] 2> at
org.apache.solr.core.SolrCore.close(SolrCore.java:1124)
[junit4] 2> at
org.apache.solr.core.SolrCores.close(SolrCores.java:117)
[junit4] 2> at
org.apache.solr.core.CoreContainer.shutdown(CoreContainer.java:365)
[junit4] 2> at
org.apache.solr.servlet.SolrDispatchFilter.destroy(SolrDispatchFilter.java:194)
[junit4] 2> at
org.eclipse.jetty.servlet.FilterHolder.destroyInstance(FilterHolder.java:153)
[junit4] 2> at
org.eclipse.jetty.servlet.FilterHolder.doStop(FilterHolder.java:131)
[junit4] 2> at
org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletHandler.doStop(ServletHandler.java:206)
[junit4] 2> at
org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.doStop(HandlerWrapper.java:107)
[junit4] 2> at
org.eclipse.jetty.server.session.SessionHandler.doStop(SessionHandler.java:136)
[junit4] 2> at
org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.doStop(HandlerWrapper.java:107)
[junit4] 2> at
org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.doStop(HandlerWrapper.java:107)
[junit4] 2> at
org.eclipse.jetty.server.handler.ContextHandler.doStop(ContextHandler.java:808)
[junit4] 2> at
org.eclipse.jetty.servlet.ServletContextHandler.doStop(ServletContextHandler.java:160)
[junit4] 2> at
org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89)
[junit4] 2> at
org.eclipse.jetty.server.handler.HandlerWrapper.doStop(HandlerWrapper.java:107)
[junit4] 2> at
org.eclipse.jetty.server.Server.doStop(Server.java:341)
[junit4] 2> at
org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:89)
[junit4] 2> at
org.apache.solr.client.solrj.embedded.JettySolrRunner.stop(JettySolrRunner.java:443)
[junit4] 2> at
org.apache.solr.SolrJettyTestBase.afterSolrJettyTestBase(SolrJettyTestBase.java:87)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native
Method)
[junit4] 2> at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:483)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
[junit4] 2> at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:790)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4] 2> at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
[junit4] 2> at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4] 2> at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 133046 T416 oasc.CachingDirectoryFactory.close Closing
MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 133046 T416 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-002
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-002;done=false>>]
[junit4] 2> 133046 T416 oasc.CachingDirectoryFactory.close Closing
directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-002
[junit4] 2> 133046 T416 oasc.CachingDirectoryFactory.closeCacheValue
looking to close
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-002/index
[CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-002/index;done=false>>]
[junit4] 2> 133047 T416 oasc.CachingDirectoryFactory.close Closing
directory:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001/tempDir-002/index
[junit4] 2> 133049 T416 oejsh.ContextHandler.doStop stopped
o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 137972 T416 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.embedded.SolrExampleJettyTest-C023B9FA8AD756D4-001
[junit4] 2> NOTE: test params are: codec=Lucene50, sim=DefaultSimilarity,
locale=en_SG, timezone=ART
[junit4] 2> NOTE: Linux 3.13.0-40-generic i386/Oracle Corporation 1.8.0_20
(32-bit)/cpus=8,threads=1,free=61990728,total=221249536
[junit4] 2> NOTE: All tests run in this JVM: [TestJavaBinCodec,
SolrExampleEmbeddedTest, LargeVolumeEmbeddedTest, SolrExampleXMLTest,
TestUpdateRequestCodec, TestEmbeddedSolrServer, NoOpResponseParserTest,
AnlysisResponseBaseTest, ModifiableSolrParamsTest, JettyWebappTest,
LargeVolumeBinaryJettyTest, LargeVolumeJettyTest, SolrPingTest,
CloudSolrServerTest, SolrExampleStreamingBinaryTest, SolrExampleJettyTest]
[junit4] Completed on J0 in 11.67s, 32 tests, 1 error <<< FAILURES!
[...truncated 82 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:529: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:477: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:61: The following
error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/extra-targets.xml:39: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:203: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:508: The
following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1363:
The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:970:
There were test failures: 54 suites, 332 tests, 1 error
Total time: 83 minutes 54 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 32bit/jdk1.8.0_20 -server
-XX:+UseParallelGC (asserts: false)
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
