Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/826/
1 tests failed.
FAILED: org.apache.solr.client.solrj.SolrExampleXMLTest.testCommitWithinOnAdd
Error Message:
expected:<0> but was:<1>
Stack Trace:
junit.framework.AssertionFailedError: expected:<0> but was:<1>
at
__randomizedtesting.SeedInfo.seed([786BFF6A1AA93394:64E4D5066B0BD39F]:0)
at junit.framework.Assert.fail(Assert.java:50)
at junit.framework.Assert.failNotEquals(Assert.java:287)
at junit.framework.Assert.assertEquals(Assert.java:67)
at junit.framework.Assert.assertEquals(Assert.java:134)
at junit.framework.Assert.assertEquals(Assert.java:140)
at
org.apache.solr.client.solrj.SolrExampleTestsBase.testCommitWithinOnAdd(SolrExampleTestsBase.java:63)
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:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
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:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 13913 lines...]
[junit4] Suite: org.apache.solr.client.solrj.SolrExampleXMLTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/init-core-data-001
[junit4] 2> 144380 WARN
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=6 numCloses=6
[junit4] 2> 144380 INFO
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields
[junit4] 2> 144382 INFO
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776)
[junit4] 2> 144427 INFO
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 144427 INFO
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 144427 INFO
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ]
o.a.s.SolrTestCaseJ4 Writing core.properties file to
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-002/cores/core
[junit4] 2> 144429 INFO
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ] o.e.j.s.Server
jetty-9.3.14.v20161028
[junit4] 2> 144528 INFO
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@31e0c6bd{/solr,null,AVAILABLE}
[junit4] 2> 144536 INFO
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@127f5e62{HTTP/1.1,[http/1.1]}{127.0.0.1:37846}
[junit4] 2> 144536 INFO
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ] o.e.j.s.Server
Started @149696ms
[junit4] 2> 144536 INFO
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=37846,
configSetBaseDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001,
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-002/cores}
[junit4] 2> 144537 ERROR
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 144537 INFO
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.6.0
[junit4] 2> 144537 INFO
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on
port null
[junit4] 2> 144537 INFO
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 144537 INFO
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-04-06T16:08:54.554Z
[junit4] 2> 144537 INFO
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ]
o.a.s.c.SolrXmlConfig Loading container configuration from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/solr.xml
[junit4] 2> 144655 INFO
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 144773 INFO
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ]
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001
[junit4] 2> 144773 INFO
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ]
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 144912 WARN (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.c.SolrConfig Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/../../../../contrib/extraction/lib
filtered by .*\.jar to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/../../../../contrib/extraction/lib
[junit4] 2> 144914 WARN (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.c.SolrConfig Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/../../../../dist
filtered by solr-cell-\d.*\.jar to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/../../../../dist
[junit4] 2> 144914 WARN (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.c.SolrConfig Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/../../../../contrib/clustering/lib
filtered by .*\.jar to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/../../../../contrib/clustering/lib
[junit4] 2> 144914 WARN (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.c.SolrConfig Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/../../../../dist
filtered by solr-clustering-\d.*\.jar to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/../../../../dist
[junit4] 2> 144915 WARN (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.c.SolrConfig Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/../../../../contrib/langid/lib
filtered by .*\.jar to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/../../../../contrib/langid/lib
[junit4] 2> 144915 WARN (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.c.SolrConfig Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/../../../../dist
filtered by solr-langid-\d.*\.jar to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/../../../../dist
[junit4] 2> 144915 WARN (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.c.SolrConfig Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/../../../../contrib/ltr/lib
filtered by .*\.jar to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/../../../../contrib/ltr/lib
[junit4] 2> 144915 WARN (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.c.SolrConfig Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/../../../../dist
filtered by solr-ltr-\d.*\.jar to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/../../../../dist
[junit4] 2> 144915 WARN (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.c.SolrConfig Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/../../../../contrib/velocity/lib
filtered by .*\.jar to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/../../../../contrib/velocity/lib
[junit4] 2> 144915 WARN (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.c.SolrConfig Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/../../../../dist
filtered by solr-velocity-\d.*\.jar to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/../../../../dist
[junit4] 2> 144948 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.SolrIndexConfig IndexWriter infoStream solr logging is
enabled
[junit4] 2> 144949 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.6.0
[junit4] 2> 145175 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example
[junit4] 2> 146118 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.r.RestManager Registered ManagedResource impl
org.apache.solr.rest.schema.analysis.ManagedWordSetResource for path
/schema/analysis/stopwords/english
[junit4] 2> 146119 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.r.RestManager Registered ManagedResource impl
org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory$SynonymManager
for path /schema/analysis/synonyms/english
[junit4] 2> 146120 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid
field id
[junit4] 2> 146120 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.r.RestManager Added observer of type
org.apache.solr.rest.schema.analysis.ManagedStopFilterFactory to existing
ManagedResource /schema/analysis/stopwords/english
[junit4] 2> 146120 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.r.RestManager Added observer of type
org.apache.solr.rest.schema.analysis.ManagedSynonymFilterFactory to existing
ManagedResource /schema/analysis/synonyms/english
[junit4] 2> 146195 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using
configuration from instancedir
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1,
trusted=true
[junit4] 2> 146196 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 146196 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/data/]
[junit4] 2> 146196 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1832bffb
[junit4] 2> 146211 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-133-thread-1]:
init: current segments file is "segments";
deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@3f02721a
[junit4] 2> 146211 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-133-thread-1]:
delete []
[junit4] 2> 146211 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-133-thread-1]:
now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 146211 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-133-thread-1]:
delete []
[junit4] 2> 146211 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-133-thread-1]:
0 msec to checkpoint
[junit4] 2> 146211 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
init: create=true
[junit4] 2> 146211 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
[junit4] 2> dir=MockDirectoryWrapper(RAMDirectory@2c072711
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6dcf03d9)
[junit4] 2> index=
[junit4] 2> version=6.6.0
[junit4] 2>
analyzer=org.apache.solr.update.SolrIndexConfig$DelayedSchemaAnalyzer
[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.solr.search.similarities.SchemaSimilarityFactory$SchemaSimilarity
[junit4] 2> mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1,
maxMergeCount=-1, ioThrottle=true
[junit4] 2> codec=Lucene62
[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.1
[junit4] 2>
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@2f3c98d5
[junit4] 2> readerPooling=false
[junit4] 2> perThreadHardLimitMB=1945
[junit4] 2> useCompoundFile=false
[junit4] 2> commitOnClose=true
[junit4] 2> indexSort=null
[junit4] 2> writer=org.apache.solr.update.SolrIndexWriter@3a961d8c
[junit4] 2>
[junit4] 2> 146211 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
MMapDirectory.UNMAP_SUPPORTED=true
[junit4] 2> 146211 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
now flush at close
[junit4] 2> 146211 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
start flush: applyAllDeletes=true
[junit4] 2> 146211 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
index before flush
[junit4] 2> 146211 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-133-thread-1]:
startFullFlush
[junit4] 2> 146211 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-133-thread-1]:
coreLoadExecutor-133-thread-1 finishFullFlush success=true
[junit4] 2> 146211 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
apply all deletes during flush
[junit4] 2> 146211 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
now apply all deletes for all segments maxDoc=0
[junit4] 2> 146211 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-133-thread-1]:
prune sis=segments: minGen=9223372036854775807 packetCount=0
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-133-thread-1]:
initDynamicDefaults spins=false maxThreadCount=2 maxMergeCount=7
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-133-thread-1]:
updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-133-thread-1]:
now merge
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-133-thread-1]:
index:
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [MS][coreLoadExecutor-133-thread-1]:
no more merges pending; now return
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
waitForMerges
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
waitForMerges done
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
commit: start
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
commit: enter lock
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
commit: now prepare
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
prepareCommit: flush
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
index before flush
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-133-thread-1]:
startFullFlush
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
apply all deletes during flush
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
now apply all deletes for all segments maxDoc=0
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-133-thread-1]:
prune sis=segments: minGen=9223372036854775807 packetCount=0
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-133-thread-1]:
coreLoadExecutor-133-thread-1 finishFullFlush success=true
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
startCommit(): start
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
startCommit index= changeCount=2
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
startCommit: wrote pending segments file "pending_segments_1"
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
done all syncs: []
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
commit: pendingCommit != null
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
commit: done writing segments file "segments_1"
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-133-thread-1]:
now checkpoint "" [0 segments ; isCommit = true]
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-133-thread-1]:
0 msec to checkpoint
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-133-thread-1]:
delete []
[junit4] 2> 146212 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
commit: took 0.6 msec
[junit4] 2> 146213 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
commit: done
[junit4] 2> 146213 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
rollback
[junit4] 2> 146213 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
all running merges have aborted
[junit4] 2> 146213 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
rollback: done finish merges
[junit4] 2> 146213 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-133-thread-1]:
abort
[junit4] 2> 146213 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-133-thread-1]:
done abort success=true
[junit4] 2> 146213 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
rollback: infos=
[junit4] 2> 146213 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-133-thread-1]:
now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 146213 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-133-thread-1]:
delete []
[junit4] 2> 146213 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-133-thread-1]:
0 msec to checkpoint
[junit4] 2> 146213 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-133-thread-1]:
delete []
[junit4] 2> 146226 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.r.XSLTResponseWriter xsltCacheLifetimeSeconds=5
[junit4] 2> 146422 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 146422 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 146423 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for
15000ms;
[junit4] 2> 146423 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 146427 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-133-thread-1]:
init: current segments file is "segments_1";
deletionPolicy=org.apache.solr.core.IndexDeletionPolicyWrapper@3f02721a
[junit4] 2> 146427 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-133-thread-1]:
init: load commit "segments_1"
[junit4] 2> 146427 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-133-thread-1]:
delete []
[junit4] 2> 146427 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-133-thread-1]:
now checkpoint "" [0 segments ; isCommit = false]
[junit4] 2> 146427 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-133-thread-1]:
delete []
[junit4] 2> 146427 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreLoadExecutor-133-thread-1]:
0 msec to checkpoint
[junit4] 2> 146427 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
init: create=false
[junit4] 2> 146427 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
[junit4] 2> dir=MockDirectoryWrapper(RAMDirectory@2c072711
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6dcf03d9)
[junit4] 2> index=
[junit4] 2> version=6.6.0
[junit4] 2>
analyzer=org.apache.solr.update.SolrIndexConfig$DelayedSchemaAnalyzer
[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.solr.search.similarities.SchemaSimilarityFactory$SchemaSimilarity
[junit4] 2> mergeScheduler=ConcurrentMergeScheduler: maxThreadCount=-1,
maxMergeCount=-1, ioThrottle=true
[junit4] 2> codec=Lucene62
[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.1
[junit4] 2>
indexerThreadPool=org.apache.lucene.index.DocumentsWriterPerThreadPool@6abc1237
[junit4] 2> readerPooling=false
[junit4] 2> perThreadHardLimitMB=1945
[junit4] 2> useCompoundFile=false
[junit4] 2> commitOnClose=true
[junit4] 2> indexSort=null
[junit4] 2> writer=org.apache.solr.update.SolrIndexWriter@1b267f07
[junit4] 2>
[junit4] 2> 146428 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
MMapDirectory.UNMAP_SUPPORTED=true
[junit4] 2> 146428 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
flush at getReader
[junit4] 2> 146428 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-133-thread-1]:
startFullFlush
[junit4] 2> 146428 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
apply all deletes during flush
[junit4] 2> 146428 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
now apply all deletes for all segments maxDoc=0
[junit4] 2> 146428 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [BD][coreLoadExecutor-133-thread-1]:
prune sis=segments_1: minGen=9223372036854775807 packetCount=0
[junit4] 2> 146428 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
incRefDeleter for NRT reader version=2 segments=
[junit4] 2> 146428 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
return reader version=2 reader=StandardDirectoryReader(segments_1:2:nrt)
[junit4] 2> 146428 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreLoadExecutor-133-thread-1]:
coreLoadExecutor-133-thread-1 finishFullFlush success=true
[junit4] 2> 146428 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreLoadExecutor-133-thread-1]:
getReader took 0 msec
[junit4] 2> 146428 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5c1fdb[collection1]
main]
[junit4] 2> 146428 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to
use dir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/conf
[junit4] 2> 146429 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path
_rest_managed.json using
file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/conf
[junit4] 2> 146429 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.r.ManagedResource Loaded initArgs {} for /rest/managed
[junit4] 2> 146429 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path
_schema_analysis_stopwords_english.json using
file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/conf
[junit4] 2> 146429 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.r.ManagedResource Loaded initArgs {ignoreCase=true} for
/schema/analysis/stopwords/english
[junit4] 2> 146429 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.r.s.a.ManagedWordSetResource Loaded 35 words for
/schema/analysis/stopwords/english
[junit4] 2> 146429 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.r.ManagedResource Notified 2 observers of
/schema/analysis/stopwords/english
[junit4] 2> 146429 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Loaded LinkedHashMap at path
_schema_analysis_synonyms_english.json using
file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/conf
[junit4] 2> 146429 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.r.ManagedResource Loaded initArgs
{ignoreCase=true,format=solr} for /schema/analysis/synonyms/english
[junit4] 2> 146429 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.r.s.a.ManagedSynonymFilterFactory Loaded 3 synonym
mappings for /schema/analysis/synonyms/english
[junit4] 2> 146430 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.r.ManagedResource Notified 2 observers of
/schema/analysis/synonyms/english
[junit4] 2> 146430 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 146430 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.s.DirectSolrSpellChecker 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> 146430 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.h.c.SuggestComponent Initializing SuggestComponent
[junit4] 2> 146430 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.s.s.SolrSuggester init:
{name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string,buildOnStartup=false}
[junit4] 2> 146431 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.s.s.SolrSuggester Dictionary loaded with params:
{name=mySuggester,lookupImpl=FuzzyLookupFactory,dictionaryImpl=DocumentDictionaryFactory,field=cat,weightField=price,suggestAnalyzerFieldType=string,buildOnStartup=false}
[junit4] 2> 146431 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001/tempDir-001/collection1/conf/elevate.xml
[junit4] 2> 146444 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 146444 INFO
(searcherExecutor-134-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.QuerySenderListener QuerySenderListener sending requests to
Searcher@5c1fdb[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 146445 INFO
(searcherExecutor-134-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=null path=null
params={q=static+firstSearcher+warming+in+solrconfig.xml&distrib=false&event=firstSearcher}
hits=0 status=0 QTime=0
[junit4] 2> 146445 INFO
(searcherExecutor-134-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 146445 INFO
(searcherExecutor-134-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 146445 INFO
(searcherExecutor-134-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 146447 INFO
(searcherExecutor-134-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@5c1fdb[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 146510 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache
for 2147483647 transient cores
[junit4] 2> 146512 INFO (coreLoadExecutor-133-thread-1) [
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent
updates, using new clock 1563945794565832704
[junit4] 2> 146867 INFO
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ]
o.a.s.SolrJettyTestBase Jetty Assigned Port#37846
[junit4] 2> 146870 INFO
(TEST-SolrExampleXMLTest.testContentStreamRequest-seed#[786BFF6A1AA93394]) [
] o.a.s.SolrTestCaseJ4 ###Starting testContentStreamRequest
[junit4] 2> 146898 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-313]: anyChanges? numDocsInRam=0
deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 146900 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-313]: anyChanges? numDocsInRam=0
deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 146900 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: nrtIsCurrent: infoVersion
matches: true; DW changes: true; BD changes: false
[junit4] 2> 146900 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: flush at getReader
[junit4] 2> 146900 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-313]: startFullFlush
[junit4] 2> 146900 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-313]: anyChanges? numDocsInRam=0
deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 146904 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-313]: qtp506142984-313: flush naked
frozen global deletes
[junit4] 2> 146904 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [BD][qtp506142984-313]: push deletes 1 deleted
queries bytesUsed=80 segmentPrivate?=false delGen=2 packetCount=1
totBytesUsed=80
[junit4] 2> 146904 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-313]: flush: push buffered updates:
1 deleted queries bytesUsed=80
[junit4] 2> 146904 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: apply all deletes during flush
[junit4] 2> 146904 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: now apply all deletes for all
segments maxDoc=0
[junit4] 2> 146904 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [BD][qtp506142984-313]: prune sis=segments_1:
minGen=9223372036854775807 packetCount=1
[junit4] 2> 146904 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [BD][qtp506142984-313]: pruneDeletes: prune 1
packets; 0 packets remain
[junit4] 2> 146904 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: incRefDeleter for NRT reader
version=2 segments=
[junit4] 2> 146904 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: return reader version=2
reader=StandardDirectoryReader(segments_1:2:nrt)
[junit4] 2> 146904 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-313]: qtp506142984-313
finishFullFlush success=true
[junit4] 2> 146904 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: getReader took 4 msec
[junit4] 2> 146904 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IFD][qtp506142984-313]: delete []
[junit4] 2> 146904 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: decRefDeleter for NRT reader
version=2 segments=
[junit4] 2> 146905 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{deleteByQuery=*:* (-1563945794966388736)} 0 11
[junit4] 2> 146917 INFO (qtp506142984-314) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 146923 INFO (qtp506142984-314) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 146924 INFO (qtp506142984-314) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 146924 INFO (qtp506142984-314) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=xml&version=2.2}{commit=}
0 13
[junit4] 2> 146926 INFO (qtp506142984-315) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/select
params={q=*:*&wt=xml&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 146941 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 146959 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@1b267f07
[junit4] 2> 146959 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: commit: start
[junit4] 2> 146959 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: commit: enter lock
[junit4] 2> 146959 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: commit: now prepare
[junit4] 2> 146959 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: prepareCommit: flush
[junit4] 2> 146959 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: index before flush
[junit4] 2> 146959 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-316]: startFullFlush
[junit4] 2> 146959 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-316]: anyChanges? numDocsInRam=10
deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 146959 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [DWFC][qtp506142984-316]: addFlushableState
DocumentsWriterPerThread [pendingDeletes=gen=0 9 deleted terms (unique count=9)
bytesUsed=1566, segment=_0, aborted=false, numDocsInRAM=10, deleteQueue=DWDQ: [
generation: 2 ]]
[junit4] 2> 146960 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [DWPT][qtp506142984-316]: flush postings as segment
_0 numDocs=10
[junit4] 2> 146970 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: 10 msec to write norms
[junit4] 2> 146972 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: 1 msec to write docValues
[junit4] 2> 146972 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: 0 msec to write points
[junit4] 2> 146972 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: 0 msec to finish stored fields
[junit4] 2> 146982 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: 9 msec to write postings and
finish vectors
[junit4] 2> 146988 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: 5 msec to write fieldInfos
[junit4] 2> 146988 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [DWPT][qtp506142984-316]: new segment has 0 deleted
docs
[junit4] 2> 146988 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [DWPT][qtp506142984-316]: new segment has no vectors;
norms; docValues; prox; freqs
[junit4] 2> 146988 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [DWPT][qtp506142984-316]:
flushedFiles=[_0_Lucene50_0.doc, _0_Lucene50_0.tim, _0_Lucene50_0.pos, _0.nvd,
_0.fdx, _0_Lucene50_0.tip, _0.fdt, _0.dii, _0_Lucene54_0.dvm,
_0_Lucene54_0.dvd, _0.dim, _0.nvm, _0.fnm]
[junit4] 2> 146988 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [DWPT][qtp506142984-316]: flushed codec=Lucene62
[junit4] 2> 146988 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [DWPT][qtp506142984-316]: flushed: segment=_0
ramUsed=0.253 MB newFlushedSize=0.006 MB docs/MB=1,811.638
[junit4] 2> 146988 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [DWPT][qtp506142984-316]: flush time 28.435742 msec
[junit4] 2> 146988 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-316]: publishFlushedSegment
seg-private updates=null
[junit4] 2> 146988 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: publishFlushedSegment
[junit4] 2> 146988 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [BD][qtp506142984-316]: push deletes 10 deleted
terms (unique count=10) bytesUsed=1072 segmentPrivate?=false delGen=4
packetCount=1 totBytesUsed=1072
[junit4] 2> 146988 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: publish sets newSegment
delGen=5 seg=_0(6.6.0):C10
[junit4] 2> 146988 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IFD][qtp506142984-316]: now checkpoint
"_0(6.6.0):C10" [1 segments ; isCommit = false]
[junit4] 2> 146988 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IFD][qtp506142984-316]: delete []
[junit4] 2> 146988 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IFD][qtp506142984-316]: 0 msec to checkpoint
[junit4] 2> 146988 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: apply all deletes during flush
[junit4] 2> 146988 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: now apply all deletes for all
segments maxDoc=10
[junit4] 2> 146989 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [BD][qtp506142984-316]: applyDeletes: open segment
readers took 1 msec
[junit4] 2> 146989 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [BD][qtp506142984-316]: applyDeletes:
infos=[_0(6.6.0):C10] packetCount=1
[junit4] 2> 146989 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [BD][qtp506142984-316]: applyDeletes took 1 msec for
1 segments, 0 newly deleted docs (query deletes), 0 visited terms,
allDeleted=null
[junit4] 2> 146989 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [BD][qtp506142984-316]: prune sis=segments_1:
_0(6.6.0):C10 minGen=5 packetCount=1
[junit4] 2> 146989 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [BD][qtp506142984-316]: pruneDeletes: prune 1
packets; 0 packets remain
[junit4] 2> 146989 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-316]: qtp506142984-316
finishFullFlush success=true
[junit4] 2> 146989 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: startCommit(): start
[junit4] 2> 146989 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: startCommit
index=_0(6.6.0):C10 changeCount=4
[junit4] 2> 146989 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: startCommit: wrote pending
segments file "pending_segments_2"
[junit4] 2> 146989 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: done all syncs: [_0.si,
_0_Lucene50_0.doc, _0_Lucene50_0.tim, _0_Lucene50_0.pos, _0.nvd, _0.fdx,
_0_Lucene50_0.tip, _0.fdt, _0.dii, _0_Lucene54_0.dvm, _0_Lucene54_0.dvd,
_0.dim, _0.nvm, _0.fnm]
[junit4] 2> 146989 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: commit: pendingCommit != null
[junit4] 2> 146989 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: commit: done writing segments
file "segments_2"
[junit4] 2> 146989 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IFD][qtp506142984-316]: now checkpoint
"_0(6.6.0):C10" [1 segments ; isCommit = true]
[junit4] 2> 146989 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IFD][qtp506142984-316]: deleteCommits: now decRef
commit "segments_1"
[junit4] 2> 146990 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IFD][qtp506142984-316]: delete [segments_1]
[junit4] 2> 146994 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IFD][qtp506142984-316]: 4 msec to checkpoint
[junit4] 2> 146994 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IFD][qtp506142984-316]: delete []
[junit4] 2> 146994 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: commit: took 34.4 msec
[junit4] 2> 146994 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: commit: done
[junit4] 2> 146994 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [TMP][qtp506142984-316]: findMerges: 1 segments
[junit4] 2> 146994 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [TMP][qtp506142984-316]: seg=_0(6.6.0):C10
size=0.006 MB [floored]
[junit4] 2> 146994 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [TMP][qtp506142984-316]: allowedSegmentCount=1 vs
count=1 (eligible count=1) tooBigCount=0
[junit4] 2> 146994 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [MS][qtp506142984-316]: initDynamicDefaults
spins=false maxThreadCount=2 maxMergeCount=7
[junit4] 2> 146994 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [MS][qtp506142984-316]: now merge
[junit4] 2> 146994 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [MS][qtp506142984-316]: index: _0(6.6.0):C10
[junit4] 2> 146994 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [MS][qtp506142984-316]: no more merges pending; now
return
[junit4] 2> 146994 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: nrtIsCurrent: infoVersion
matches: false; DW changes: false; BD changes: false
[junit4] 2> 146994 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: flush at getReader
[junit4] 2> 146994 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-316]: startFullFlush
[junit4] 2> 146994 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: apply all deletes during flush
[junit4] 2> 146994 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: now apply all deletes for all
segments maxDoc=10
[junit4] 2> 146994 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [BD][qtp506142984-316]: applyDeletes: open segment
readers took 0 msec
[junit4] 2> 146995 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [BD][qtp506142984-316]: applyDeletes: no segments;
skipping
[junit4] 2> 146995 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [BD][qtp506142984-316]: prune sis=segments_2:
_0(6.6.0):C10 minGen=5 packetCount=0
[junit4] 2> 146996 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: incRefDeleter for NRT reader
version=6 segments=_0(6.6.0):C10
[junit4] 2> 146998 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: return reader version=6
reader=StandardDirectoryReader(segments_2:6:nrt _0(6.6.0):C10)
[junit4] 2> 146998 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-316]: qtp506142984-316
finishFullFlush success=true
[junit4] 2> 146998 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-316]: getReader took 4 msec
[junit4] 2> 146998 INFO (qtp506142984-316) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@4153d54d[collection1] main]
[junit4] 2> 146999 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 147000 INFO
(searcherExecutor-134-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.QuerySenderListener QuerySenderListener sending requests to
Searcher@4153d54d[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C10)))}
[junit4] 2> 147000 INFO
(searcherExecutor-134-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 147002 INFO
(searcherExecutor-134-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@4153d54d[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.6.0):C10)))}
[junit4] 2> 147003 INFO
(searcherExecutor-134-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.u.LoggingInfoStream
[IFD][searcherExecutor-134-thread-1-processing-x:collection1]: delete []
[junit4] 2> 147003 INFO
(searcherExecutor-134-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.u.LoggingInfoStream
[IW][searcherExecutor-134-thread-1-processing-x:collection1]: decRefDeleter for
NRT reader version=2 segments=
[junit4] 2> 147003 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=xml&version=2.2}{add=[0553573403
(1563945795009380352), 0553579908 (1563945795013574656), 055357342X
(1563945795013574657), 0553293354 (1563945795013574658), 0812521390
(1563945795014623232), 0812550706 (1563945795014623233), 0441385532
(1563945795014623234), 0380014300 (1563945795014623235), 0805080481
(1563945795015671808), 080508049X (1563945795015671809)],commit=} 0 71
[junit4] 2> 147006 INFO (qtp506142984-317) [ x:collection1]
o.a.s.c.S.Request [collection1] webapp=/solr path=/select
params={q=*:*&wt=xml&version=2.2} hits=10 status=0 QTime=0
[junit4] 2> 147010 INFO
(TEST-SolrExampleXMLTest.testContentStreamRequest-seed#[786BFF6A1AA93394]) [
] o.a.s.SolrTestCaseJ4 ###Ending testContentStreamRequest
[junit4] 2> 147023 INFO
(TEST-SolrExampleXMLTest.testChildDoctransformer-seed#[786BFF6A1AA93394]) [
] o.a.s.SolrTestCaseJ4 ###Starting testChildDoctransformer
[junit4] 2> 147028 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-317]: anyChanges? numDocsInRam=0
deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 147028 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-317]: anyChanges? numDocsInRam=0
deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 147028 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-317]: nrtIsCurrent: infoVersion
matches: true; DW changes: true; BD changes: false
[junit4] 2> 147028 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-317]: flush at getReader
[junit4] 2> 147028 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-317]: startFullFlush
[junit4] 2> 147028 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-317]: anyChanges? numDocsInRam=0
deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 147028 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-317]: qtp506142984-317: flush naked
frozen global deletes
[junit4] 2> 147028 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [BD][qtp506142984-317]: push deletes 1 deleted
queries bytesUsed=80 segmentPrivate?=false delGen=8 packetCount=1
totBytesUsed=80
[junit4] 2> 147028 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-317]: flush: push buffered updates:
1 deleted queries bytesUsed=80
[junit4] 2> 147028 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-317]: apply all deletes during flush
[junit4] 2> 147028 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-317]: now apply all deletes for all
segments maxDoc=10
[junit4] 2> 147028 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [BD][qtp506142984-317]: applyDeletes: open segment
readers took 0 msec
[junit4] 2> 147028 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [BD][qtp506142984-317]: applyDeletes:
infos=[_0(6.6.0):C10] packetCount=1
[junit4] 2> 147034 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [BD][qtp506142984-317]: applyDeletes: 10 new deleted
documents
[junit4] 2> 147034 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [BD][qtp506142984-317]: applyDeletes took 6 msec for
1 segments, 10 newly deleted docs (query deletes), 0 visited terms,
allDeleted=[_0(6.6.0):C10]
[junit4] 2> 147034 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [IFD][qtp506142984-317]: now checkpoint
"_0(6.6.0):C10/10" [1 segments ; isCommit = false]
[junit4] 2> 147034 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [IFD][qtp506142984-317]: delete []
[junit4] 2> 147034 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [IFD][qtp506142984-317]: 0 msec to checkpoint
[junit4] 2> 147034 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-317]: drop 100% deleted segments:
_0(6.6.0):C10/10
[junit4] 2> 147034 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [IFD][qtp506142984-317]: now checkpoint "" [0
segments ; isCommit = false]
[junit4] 2> 147034 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [IFD][qtp506142984-317]: delete []
[junit4] 2> 147034 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [IFD][qtp506142984-317]: 0 msec to checkpoint
[junit4] 2> 147034 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [BD][qtp506142984-317]: prune sis=segments_2:
minGen=9223372036854775807 packetCount=1
[junit4] 2> 147035 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [BD][qtp506142984-317]: pruneDeletes: prune 1
packets; 0 packets remain
[junit4] 2> 147035 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-317]: incRefDeleter for NRT reader
version=8 segments=
[junit4] 2> 147035 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-317]: return reader version=8
reader=StandardDirectoryReader(segments_2:8:nrt)
[junit4] 2> 147035 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-317]: qtp506142984-317
finishFullFlush success=true
[junit4] 2> 147035 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [TMP][qtp506142984-317]: findMerges: 0 segments
[junit4] 2> 147035 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [MS][qtp506142984-317]: now merge
[junit4] 2> 147035 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [MS][qtp506142984-317]: index:
[junit4] 2> 147035 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [MS][qtp506142984-317]: no more merges pending; now
return
[junit4] 2> 147035 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-317]: getReader took 7 msec
[junit4] 2> 147035 INFO (qtp506142984-317) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@223a53b9[collection1] realtime]
[junit4] 2> 147035 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{deleteByQuery=*:* (-1563945795105849344)} 0 8
[junit4] 2> 147039 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 147039 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@1b267f07
[junit4] 2> 147039 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: commit: start
[junit4] 2> 147039 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: commit: enter lock
[junit4] 2> 147039 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: commit: now prepare
[junit4] 2> 147039 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: prepareCommit: flush
[junit4] 2> 147039 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: index before flush
[junit4] 2> 147039 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-313]: startFullFlush
[junit4] 2> 147039 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: apply all deletes during flush
[junit4] 2> 147039 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: now apply all deletes for all
segments maxDoc=0
[junit4] 2> 147039 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [BD][qtp506142984-313]: prune sis=segments_2:
minGen=9223372036854775807 packetCount=0
[junit4] 2> 147039 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-313]: qtp506142984-313
finishFullFlush success=true
[junit4] 2> 147039 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: startCommit(): start
[junit4] 2> 147039 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: startCommit index=
changeCount=8
[junit4] 2> 147040 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: startCommit: wrote pending
segments file "pending_segments_3"
[junit4] 2> 147040 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: done all syncs: []
[junit4] 2> 147040 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: commit: pendingCommit != null
[junit4] 2> 147040 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: commit: done writing segments
file "segments_3"
[junit4] 2> 147040 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IFD][qtp506142984-313]: now checkpoint "" [0
segments ; isCommit = true]
[junit4] 2> 147040 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IFD][qtp506142984-313]: deleteCommits: now decRef
commit "segments_2"
[junit4] 2> 147040 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IFD][qtp506142984-313]: delete [segments_2]
[junit4] 2> 147040 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IFD][qtp506142984-313]: 0 msec to checkpoint
[junit4] 2> 147040 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IFD][qtp506142984-313]: delete []
[junit4] 2> 147040 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: commit: took 0.8 msec
[junit4] 2> 147040 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: commit: done
[junit4] 2> 147040 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: nrtIsCurrent: infoVersion
matches: false; DW changes: false; BD changes: false
[junit4] 2> 147040 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: flush at getReader
[junit4] 2> 147040 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-313]: startFullFlush
[junit4] 2> 147040 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: apply all deletes during flush
[junit4] 2> 147040 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: now apply all deletes for all
segments maxDoc=0
[junit4] 2> 147040 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [BD][qtp506142984-313]: prune sis=segments_3:
minGen=9223372036854775807 packetCount=0
[junit4] 2> 147040 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: incRefDeleter for NRT reader
version=10 segments=
[junit4] 2> 147040 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: return reader version=10
reader=StandardDirectoryReader(segments_3:10:nrt)
[junit4] 2> 147040 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-313]: qtp506142984-313
finishFullFlush success=true
[junit4] 2> 147040 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: getReader took 0 msec
[junit4] 2> 147040 INFO (qtp506142984-313) [ x:collection1]
o.a.s.s.SolrIndexSearcher Opening [Searcher@63fb1b83[collection1] main]
[junit4] 2> 147041 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IFD][qtp506142984-313]: delete []
[junit4] 2> 147041 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-313]: decRefDeleter for NRT reader
version=8 segments=
[junit4] 2> 147041 INFO
(searcherExecutor-134-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.QuerySenderListener QuerySenderListener sending requests to
Searcher@63fb1b83[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 147041 INFO
(searcherExecutor-134-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 147043 INFO
(searcherExecutor-134-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@63fb1b83[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 147044 INFO
(searcherExecutor-134-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.u.LoggingInfoStream
[IFD][searcherExecutor-134-thread-1-processing-x:collection1]: delete [_0.si,
_0_Lucene50_0.doc, _0_Lucene50_0.tim, _0_Lucene50_0.pos, _0.nvd, _0.fdx,
_0_Lucene50_0.tip, _0.fdt, _0.dii, _0_Lucene54_0.dvm, _0_Lucene54_0.dvd,
_0.dim, _0.nvm, _0.fnm]
[junit4] 2> 147044 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 147044 INFO
(searcherExecutor-134-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.u.LoggingInfoStream
[IW][searcherExecutor-134-thread-1-processing-x:collection1]: decRefDeleter for
NRT reader version=6 segments=_0(6.6.0):C10
[junit4] 2> 147044 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=xml&version=2.2}{commit=}
0 6
[junit4] 2> 147059 INFO (qtp506142984-314) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[0 (1563945795128918016)]} 0 11
[junit4] 2> 147067 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[260 (1563945795143598080)]} 0 5
[junit4] 2> 147069 INFO (qtp506142984-310) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[519 (1563945795149889536)]} 0 0
[junit4] 2> 147071 INFO (qtp506142984-310) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[520 (1563945795151986688)]} 0 0
[junit4] 2> 147073 INFO (qtp506142984-310) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[521 (1563945795154083840)]} 0 0
[junit4] 2> 147075 INFO (qtp506142984-310) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[522 (1563945795156180992)]} 0 0
[junit4] 2> 147078 INFO (qtp506142984-310) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[523 (1563945795158278144)]} 0 0
[junit4] 2> 147083 INFO (qtp506142984-310) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[540 (1563945795161423872)]} 0 2
[junit4] 2> 147089 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[660 (1563945795167715328)]} 0 4
[junit4] 2> 147091 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[867 (1563945795172958208)]} 0 0
[junit4] 2> 147095 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[869 (1563945795176103936)]} 0 1
[junit4] 2> 147123 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[947 (1563945795181346816)]} 0 25
[junit4] 2> 147130 INFO (qtp506142984-310) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[1223 (1563945795210706944)]} 0 3
[junit4] 2> 147137 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[1386 (1563945795216998400)]} 0 4
[junit4] 2> 147139 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[1598 (1563945795223289856)]} 0 0
[junit4] 2> 147143 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[1601 (1563945795225387008)]} 0 1
[junit4] 2> 147145 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[1685 (1563945795229581312)]} 0 0
[junit4] 2> 147147 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[1686 (1563945795231678464)]} 0 0
[junit4] 2> 147149 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[1691 (1563945795233775616)]} 0 0
[junit4] 2> 147151 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[1692 (1563945795235872768)]} 0 0
[junit4] 2> 147153 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[1695 (1563945795237969920)]} 0 0
[junit4] 2> 147160 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[1698 (1563945795242164224)]} 0 4
[junit4] 2> 147164 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[1908 (1563945795248455680)]} 0 1
[junit4] 2> 147589 INFO (qtp506142984-314) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[1981 (1563945795688857600)]} 0 18
[junit4] 2> 147599 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[2290 (1563945795701440512)]} 0 4
[junit4] 2> 147611 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[2462 (1563945795714023424)]} 0 4
[junit4] 2> 147618 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[2674 (1563945795725557760)]} 0 0
[junit4] 2> 147623 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[2675 (1563945795729752064)]} 0 1
[junit4] 2> 147631 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[2712 (1563945795738140672)]} 0 0
[junit4] 2> 147642 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[2731 (1563945795747577856)]} 0 3
[junit4] 2> 147657 INFO (qtp506142984-310) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[2860 (1563945795761209344)]} 0 6
[junit4] 2> 147910 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[3081 (1563945796029644800)]} 0 2
[junit4] 2> 147919 INFO (qtp506142984-314) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[3195 (1563945796036984832)]} 0 4
[junit4] 2> 147922 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[3384 (1563945796044324864)]} 0 0
[junit4] 2> 147933 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[3385 (1563945796053762048)]} 0 3
[junit4] 2> 147944 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[3514 (1563945796063199232)]} 0 5
[junit4] 2> 147956 INFO (qtp506142984-314) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[3747 (1563945796075782144)]} 0 5
[junit4] 2> 147966 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[3984 (1563945796087316480)]} 0 4
[junit4] 2> 147979 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[4133 (1563945796100947968)]} 0 4
[junit4] 2> 147982 INFO (qtp506142984-310) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[4313 (1563945796107239424)]} 0 0
[junit4] 2> 147989 INFO (qtp506142984-310) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[4314 (1563945796111433728)]} 0 4
[junit4] 2> 147998 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[4500 (1563945796120870912)]} 0 3
[junit4] 2> 148006 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[4654 (1563945796129259520)]} 0 3
[junit4] 2> 148012 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[4814 (1563945796137648128)]} 0 1
[junit4] 2> 148029 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[4874 (1563945796151279616)]} 0 6
[junit4] 2> 148038 INFO (qtp506142984-314) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[5150 (1563945796162813952)]} 0 4
[junit4] 2> 148044 INFO (qtp506142984-310) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[5335 (1563945796172251136)]} 0 0
[junit4] 2> 148054 INFO (qtp506142984-310) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[5336 (1563945796179591168)]} 0 3
[junit4] 2> 148087 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[5487 (1563945796190076928)]} 0 29
[junit4] 2> 148096 INFO (qtp506142984-314) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[5826 (1563945796224679936)]} 0 2
[junit4] 2> 148099 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[5898 (1563945796228874240)]} 0 1
[junit4] 2> 148108 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[5960 (1563945796234117120)]} 0 6
[junit4] 2> 148170 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[6254 (1563945796243554304)]} 0 58
[junit4] 2> 148175 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[6568 (1563945796307517440)]} 0 2
[junit4] 2> 148179 INFO (qtp506142984-314) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[6662 (1563945796312760320)]} 0 1
[junit4] 2> 148181 INFO (qtp506142984-310) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[6710 (1563945796314857472)]} 0 0
[junit4] 2> 148186 INFO (qtp506142984-310) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[6711 (1563945796319051776)]} 0 3
[junit4] 2> 148188 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[6856 (1563945796323246080)]} 0 0
[junit4] 2> 148190 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[6857 (1563945796325343232)]} 0 0
[junit4] 2> 148192 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[6858 (1563945796327440384)]} 0 0
[junit4] 2> 148195 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[6859 (1563945796329537536)]} 0 0
[junit4] 2> 148199 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[6878 (1563945796332683264)]} 0 2
[junit4] 2> 148201 INFO (qtp506142984-310) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[6982 (1563945796336877568)]} 0 0
[junit4] 2> 148206 INFO (qtp506142984-310) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[6983 (1563945796340023296)]} 0 2
[junit4] 2> 148211 INFO (qtp506142984-314) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[7085 (1563945796345266176)]} 0 3
[junit4] 2> 148220 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[7202 (1563945796351557632)]} 0 6
[junit4] 2> 148227 INFO (qtp506142984-317) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[7508 (1563945796364140544)]} 0 0
[junit4] 2> 148244 INFO (qtp506142984-313) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[7509 (1563945796375674880)]} 0 10
[junit4] 2> 148267 INFO (qtp506142984-314) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[7821 (1563945796389306368)]} 0 17
[junit4] 2> 148270 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[8122 (1563945796409229312)]} 0 0
[junit4] 2> 148272 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[8123 (1563945796411326464)]} 0 0
[junit4] 2> 148285 INFO (qtp506142984-316) [ x:collection1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update
params={wt=xml&version=2.2}{add=[8125 (1563945796421812224)]} 0 9
[junit4] 2> 148292 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 148292 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@1b267f07
[junit4] 2> 148292 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-315]: commit: start
[junit4] 2> 148292 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-315]: commit: enter lock
[junit4] 2> 148292 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-315]: commit: now prepare
[junit4] 2> 148292 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-315]: prepareCommit: flush
[junit4] 2> 148292 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-315]: index before flush
[junit4] 2> 148292 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-315]: startFullFlush
[junit4] 2> 148292 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.LoggingInfoStream [DW][qtp506142984-315]: anyChanges? numDocsInRam=8213
deletes=true hasTickets:false pendingChangesInFullFlush: false
[junit4] 2> 148292 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.LoggingInfoStream [DWFC][qtp506142984-315]: addFlushableState
DocumentsWriterPerThread [pendingDeletes=gen=0 72 deleted terms (unique
count=72) bytesUsed=12522, segment=_1, aborted=false, numDocsInRAM=8213,
deleteQueue=DWDQ: [ generation: 7 ]]
[junit4] 2> 148292 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.LoggingInfoStream [DWPT][qtp506142984-315]: flush postings as segment
_1 numDocs=8213
[junit4] 2> 148294 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-315]: 1 msec to write norms
[junit4] 2> 148308 INFO (qtp506142984-315) [ x:collection1]
o.a.s.u.LoggingInfoStream [IW][qtp506142984-315]: 13 msec to write
[...truncated too long message...]
:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@5c48a483
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
now flush at close
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
start flush: applyAllDeletes=true
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
index before flush _1e(6.6.0):C10
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-139-thread-1]:
startFullFlush
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-139-thread-1]:
coreCloseExecutor-139-thread-1 finishFullFlush success=true
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
apply all deletes during flush
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
now apply all deletes for all segments maxDoc=10
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [BD][coreCloseExecutor-139-thread-1]:
applyDeletes: open segment readers took 0 msec
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [BD][coreCloseExecutor-139-thread-1]:
applyDeletes: no segments; skipping
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [BD][coreCloseExecutor-139-thread-1]:
prune sis=segments_21: _1e(6.6.0):C10 minGen=326 packetCount=0
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [MS][coreCloseExecutor-139-thread-1]:
updateMergeThreads ioThrottle=true targetMBPerSec=10240.0 MB/sec
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [MS][coreCloseExecutor-139-thread-1]:
now merge
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [MS][coreCloseExecutor-139-thread-1]:
index: _1e(6.6.0):C10
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [MS][coreCloseExecutor-139-thread-1]:
no more merges pending; now return
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
waitForMerges
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
waitForMerges done
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
commit: start
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
commit: enter lock
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
commit: now prepare
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
prepareCommit: flush
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
index before flush _1e(6.6.0):C10
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-139-thread-1]:
startFullFlush
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
apply all deletes during flush
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
now apply all deletes for all segments maxDoc=10
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [BD][coreCloseExecutor-139-thread-1]:
applyDeletes: open segment readers took 0 msec
[junit4] 2> 154407 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [BD][coreCloseExecutor-139-thread-1]:
applyDeletes: no segments; skipping
[junit4] 2> 154408 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [BD][coreCloseExecutor-139-thread-1]:
prune sis=segments_21: _1e(6.6.0):C10 minGen=326 packetCount=0
[junit4] 2> 154408 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-139-thread-1]:
coreCloseExecutor-139-thread-1 finishFullFlush success=true
[junit4] 2> 154408 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
startCommit(): start
[junit4] 2> 154408 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
skip startCommit(): no changes pending
[junit4] 2> 154408 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreCloseExecutor-139-thread-1]:
delete []
[junit4] 2> 154408 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
commit: pendingCommit == null; skip
[junit4] 2> 154408 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
commit: took 0.4 msec
[junit4] 2> 154408 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
commit: done
[junit4] 2> 154410 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
rollback
[junit4] 2> 154410 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
all running merges have aborted
[junit4] 2> 154410 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
rollback: done finish merges
[junit4] 2> 154410 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-139-thread-1]:
abort
[junit4] 2> 154410 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [DW][coreCloseExecutor-139-thread-1]:
done abort success=true
[junit4] 2> 154410 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IW][coreCloseExecutor-139-thread-1]:
rollback: infos=_1e(6.6.0):C10
[junit4] 2> 154410 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreCloseExecutor-139-thread-1]:
now checkpoint "_1e(6.6.0):C10" [1 segments ; isCommit = false]
[junit4] 2> 154410 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreCloseExecutor-139-thread-1]:
delete []
[junit4] 2> 154410 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreCloseExecutor-139-thread-1]:
0 msec to checkpoint
[junit4] 2> 154414 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreCloseExecutor-139-thread-1]:
delete []
[junit4] 2> 154414 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.u.LoggingInfoStream [IFD][coreCloseExecutor-139-thread-1]:
delete []
[junit4] 2> 154474 INFO (coreCloseExecutor-139-thread-1) [
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.collection1
[junit4] 2> 154475 INFO
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 154475 INFO
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@31e0c6bd{/solr,null,UNAVAILABLE}
[junit4] 2> 154477 INFO
(SUITE-SolrExampleXMLTest-seed#[786BFF6A1AA93394]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J1/temp/solr.client.solrj.SolrExampleXMLTest_786BFF6A1AA93394-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {},
docValues:{}, maxPointsInLeafNode=903, maxMBSortInHeap=7.179977395017074,
sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=ar-IQ,
timezone=Iceland
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation
1.8.0_121 (64-bit)/cpus=4,threads=1,free=210672096,total=338165760
[junit4] 2> NOTE: All tests run in this JVM: [CloudSolrClientCacheTest,
TestCloudSolrClientConnections, TestFastInputStream, CubedRootEvaluatorTest,
JdbcDriverTest, TangentEvaluatorTest, TestClusteringResponse,
ConcurrentUpdateSolrClientBuilderTest, AddEvaluatorTest, QueryResponseTest,
StreamExpressionToExplanationTest, SolrQueryTest, DocumentAnalysisResponseTest,
SolrExampleEmbeddedTest, TestDocumentObjectBinder, MergeIndexesEmbeddedTest,
SolrExampleStreamingBinaryTest, GetByIdTest, SineEvaluatorTest,
TestSolrJErrorHandling, ClientUtilsTest, SolrExampleXMLTest]
[junit4] Completed [66/128 (1!)] on J1 in 10.19s, 35 tests, 1 failure <<<
FAILURES!
[...truncated 42750 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
