You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2016/02/23 18:43:23 UTC
[JENKINS] Lucene-Solr-NightlyTests-5.5 - Build # 10 - Still Failing
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.5/10/
3 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.hdfs.HdfsChaosMonkeySafeLeaderTest
Error Message:
ObjectTracker found 1 object(s) that were not released!!! [HdfsTransactionLog]
Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [HdfsTransactionLog]
at __randomizedtesting.SeedInfo.seed([BF6E80FB6238D5D5]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:228)
at sun.reflect.GeneratedMethodAccessor50.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
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:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: org.apache.solr.core.TestNRTOpen.testSharedCores
Error Message:
expected:<3> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<3> but was:<2>
at __randomizedtesting.SeedInfo.seed([BF6E80FB6238D5D5:596DCAB4EACEF8DA]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.core.TestNRTOpen.testSharedCores(TestNRTOpen.java:116)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
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:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
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:367)
at java.lang.Thread.run(Thread.java:745)
FAILED: junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandler
Error Message:
ObjectTracker found 1 object(s) that were not released!!! [NRTCachingDirectory]
Stack Trace:
java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [NRTCachingDirectory]
at __randomizedtesting.SeedInfo.seed([BF6E80FB6238D5D5]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:228)
at sun.reflect.GeneratedMethodAccessor21.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:834)
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:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11345 lines...]
[junit4] Suite: org.apache.solr.core.TestNRTOpen
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001
[junit4] 2> 1257294 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 1257294 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1257294 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1'
[junit4] 2> 1257294 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1257295 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr
[junit4] 2> 1257295 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1257295 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1257324 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1257336 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 1257361 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 1257365 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=minimal
[junit4] 2> 1257370 WARN (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.s.IndexSchema no uniqueKey specified in schema.
[junit4] 2> 1257371 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1257371 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr
[junit4] 2> 1257371 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr'
[junit4] 2> 1257371 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1257371 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr
[junit4] 2> 1257372 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.CoreContainer New CoreContainer 18464373
[junit4] 2> 1257372 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr]
[junit4] 2> 1257372 WARN (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/lib
[junit4] 2> 1257372 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 1257374 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 1257374 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1257375 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1257375 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1257375 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1257376 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {collection=collection1, shard=shard1, name=collection1, loadOnStartup=true, config=solrconfig-basic.xml, transient=false, schema=schema-minimal.xml, configSetProperties=configsetprops.json, dataDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001}
[junit4] 2> 1257376 INFO (coreLoadExecutor-2028-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1'
[junit4] 2> 1257376 INFO (coreLoadExecutor-2028-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1257376 INFO (coreLoadExecutor-2028-thread-1) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr
[junit4] 2> 1257377 INFO (coreLoadExecutor-2028-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1257377 INFO (coreLoadExecutor-2028-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1257410 INFO (coreLoadExecutor-2028-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1257423 INFO (coreLoadExecutor-2028-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 1257451 INFO (coreLoadExecutor-2028-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 1257456 INFO (coreLoadExecutor-2028-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=minimal
[junit4] 2> 1257463 WARN (coreLoadExecutor-2028-thread-1) [ ] o.a.s.s.IndexSchema no uniqueKey specified in schema.
[junit4] 2> 1257465 INFO (coreLoadExecutor-2028-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1'
[junit4] 2> 1257465 INFO (coreLoadExecutor-2028-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1
[junit4] 2> 1257465 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.StandardDirectoryFactory
[junit4] 2> 1257474 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/]
[junit4] 2> 1257475 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 1257475 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001
[junit4] 2> 1257475 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index/
[junit4] 2> 1257475 WARN (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index' doesn't exist. Creating new index...
[junit4] 2> 1257476 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index
[junit4] 2> 1257476 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=91.6298828125, floorSegmentMB=1.00390625, forceMergeDeletesPctAllowed=28.288175888676086, segmentsPerTier=25.0, maxCFSSegmentSizeMB=1.33203125, noCFSRatio=0.5521391798241942
[junit4] 2> 1257483 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index,segFN=segments_1,generation=1}
[junit4] 2> 1257483 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1257483 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/
[junit4] 2> 1257485 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1257504 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1257506 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1257507 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1257508 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1257510 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/ping,/admin/file,/update/json,/admin/logging,/admin/mbeans,/admin/properties,/update/json/docs,/schema,/admin/luke,/admin/system,/replication,/config,/update/csv,/update,/get,/admin/segments,/admin/threads,/admin/plugins,standard
[junit4] 2> 1257511 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1257512 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1257512 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1257512 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=32, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1257513 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index,segFN=segments_1,generation=1}
[junit4] 2> 1257513 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1257513 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@3bf3ed81[collection1] main
[junit4] 2> 1257513 WARN (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 1257513 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 1257513 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 1257513 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1257514 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1257514 INFO (searcherExecutor-2029-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3bf3ed81[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1257514 INFO (coreLoadExecutor-2028-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1257515 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1257516 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[(null)]} 0 1
[junit4] 2> 1257520 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1257535 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index,segFN=segments_1,generation=1}
[junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index,segFN=segments_2,generation=2}
[junit4] 2> 1257535 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 1257537 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.s.SolrIndexSearcher Opening Searcher@69e2f637[collection1] main
[junit4] 2> 1257537 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1257537 INFO (searcherExecutor-2029-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@69e2f637[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.5.0):c1)))}
[junit4] 2> 1257537 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 17
[junit4] 2> 1257539 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1257539 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=18464373
[junit4] 2> 1257540 INFO (coreCloseExecutor-2033-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@562aa58d
[junit4] 2> 1257540 INFO (coreCloseExecutor-2033-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=1,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 1257541 INFO (coreCloseExecutor-2033-thread-1) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 1257541 INFO (coreCloseExecutor-2033-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1257541 INFO (coreCloseExecutor-2033-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 1257541 INFO (coreCloseExecutor-2033-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 1257542 INFO (coreCloseExecutor-2033-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1257542 INFO (coreCloseExecutor-2033-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001 [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001;done=false>>]
[junit4] 2> 1257542 INFO (coreCloseExecutor-2033-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001
[junit4] 2> 1257542 INFO (coreCloseExecutor-2033-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index;done=false>>]
[junit4] 2> 1257542 INFO (coreCloseExecutor-2033-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index
[junit4] 2> 1257542 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 1257542 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1'
[junit4] 2> 1257543 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1257543 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr
[junit4] 2> 1257543 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1257543 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1257570 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1257582 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 1257608 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 1257613 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=minimal
[junit4] 2> 1257619 WARN (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.s.IndexSchema no uniqueKey specified in schema.
[junit4] 2> 1257620 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1257620 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr
[junit4] 2> 1257620 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr'
[junit4] 2> 1257620 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1257620 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr
[junit4] 2> 1257620 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.CoreContainer New CoreContainer 1773166086
[junit4] 2> 1257620 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr]
[junit4] 2> 1257621 WARN (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/lib
[junit4] 2> 1257621 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 1257622 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 1257623 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1257623 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1257623 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1257623 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1257624 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {config=solrconfig-basic.xml, transient=false, loadOnStartup=true, collection=collection1, name=collection1, schema=schema-minimal.xml, shard=shard1, configSetProperties=configsetprops.json, dataDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001}
[junit4] 2> 1257624 INFO (coreLoadExecutor-2039-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1'
[junit4] 2> 1257624 INFO (coreLoadExecutor-2039-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1257624 INFO (coreLoadExecutor-2039-thread-1) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr
[junit4] 2> 1257625 INFO (coreLoadExecutor-2039-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1257625 INFO (coreLoadExecutor-2039-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1257658 INFO (coreLoadExecutor-2039-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1257673 INFO (coreLoadExecutor-2039-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 1257702 INFO (coreLoadExecutor-2039-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 1257708 INFO (coreLoadExecutor-2039-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=minimal
[junit4] 2> 1257715 WARN (coreLoadExecutor-2039-thread-1) [ ] o.a.s.s.IndexSchema no uniqueKey specified in schema.
[junit4] 2> 1257717 INFO (coreLoadExecutor-2039-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1'
[junit4] 2> 1257717 INFO (coreLoadExecutor-2039-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1
[junit4] 2> 1257718 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.StandardDirectoryFactory
[junit4] 2> 1257718 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/]
[junit4] 2> 1257718 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 1257718 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001
[junit4] 2> 1257718 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index/
[junit4] 2> 1257719 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/
[junit4] 2> 1257721 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1257724 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1257725 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1257726 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1257728 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1257729 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/ping,/admin/file,/update/json,/admin/logging,/admin/mbeans,/admin/properties,/update/json/docs,/schema,/admin/luke,/admin/system,/replication,/config,/update/csv,/update,/get,/admin/segments,/admin/threads,/admin/plugins,standard
[junit4] 2> 1257731 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1257731 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1257731 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1257731 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index
[junit4] 2> 1257732 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=91.6298828125, floorSegmentMB=1.00390625, forceMergeDeletesPctAllowed=28.288175888676086, segmentsPerTier=25.0, maxCFSSegmentSizeMB=1.33203125, noCFSRatio=0.5521391798241942
[junit4] 2> 1257733 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index,segFN=segments_2,generation=2}
[junit4] 2> 1257733 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 1257735 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@6fd60f2e[collection1] main
[junit4] 2> 1257735 WARN (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 1257736 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 1257736 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 1257736 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1257736 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1257736 INFO (searcherExecutor-2040-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6fd60f2e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.5.0):c1)))}
[junit4] 2> 1257736 INFO (coreLoadExecutor-2039-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1257737 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 1257742 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testReaderIsNRT
[junit4] 2> 1257742 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1257743 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 1257745 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[(null)]} 0 0
[junit4] 2> 1257745 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1257760 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index,segFN=segments_2,generation=2}
[junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index,segFN=segments_3,generation=3}
[junit4] 2> 1257761 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 1257763 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.s.SolrIndexSearcher Opening Searcher@9012bb9[collection1] main
[junit4] 2> 1257763 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1257763 INFO (searcherExecutor-2040-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@9012bb9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.5.0):c1)))}
[junit4] 2> 1257763 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 18
[junit4] 2> 1257764 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1'
[junit4] 2> 1257765 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1257765 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr
[junit4] 2> 1257765 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1257765 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1257794 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1257806 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 1257831 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 1257836 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.s.IndexSchema [collection1] Schema name=minimal
[junit4] 2> 1257842 WARN (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.s.IndexSchema no uniqueKey specified in schema.
[junit4] 2> 1257845 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1'
[junit4] 2> 1257845 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1
[junit4] 2> 1257846 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/]
[junit4] 2> 1257846 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 1257846 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index/
[junit4] 2> 1257846 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/
[junit4] 2> 1257849 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1257852 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1257853 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1257855 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1257856 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1257858 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/ping,/admin/file,/update/json,/admin/logging,/admin/mbeans,/admin/properties,/update/json/docs,/schema,/admin/luke,/admin/system,/replication,/config,/update/csv,/update,/get,/admin/segments,/admin/threads,/admin/plugins,standard
[junit4] 2> 1257859 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1257859 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1257859 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1257860 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@1c96f8b9[collection1] main
[junit4] 2> 1257860 WARN (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 1257860 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 1257860 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 1257860 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1257860 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1257860 INFO (searcherExecutor-2044-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c96f8b9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.5.0):c1)))}
[junit4] 2> 1257860 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.DefaultSolrCoreState Closing old IndexWriter... core=collection1
[junit4] 2> 1257861 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=38, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=46.9521484375, floorSegmentMB=0.478515625, forceMergeDeletesPctAllowed=8.046980479419295, segmentsPerTier=20.0, maxCFSSegmentSizeMB=1.509765625, noCFSRatio=0.11725276609649882
[junit4] 2> 1257863 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index,segFN=segments_3,generation=3}
[junit4] 2> 1257863 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 3
[junit4] 2> 1257863 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1257864 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@3586874f[collection1] main
[junit4] 2> 1257864 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.CoreContainer replacing core: collection1
[junit4] 2> 1257864 INFO (searcherExecutor-2044-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3586874f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.5.0):c1)))}
[junit4] 2> 1257864 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@671ea594
[junit4] 2> 1257865 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=1,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 1257865 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 1257866 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[(null)]} 0 0
[junit4] 2> 1257867 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 1257874 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@22045601[collection1] main
[junit4] 2> 1257874 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1257875 INFO (searcherExecutor-2044-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@22045601[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.5.0):c1) Uninverting(_2(5.5.0):c1)))}
[junit4] 2> 1257875 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 7
[junit4] 2> 1257876 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[(null)]} 0 0
[junit4] 2> 1257877 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1257895 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index,segFN=segments_3,generation=3}
[junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index,segFN=segments_4,generation=4}
[junit4] 2> 1257896 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 4
[junit4] 2> 1257897 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@4e0b8438[collection1] main
[junit4] 2> 1257897 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1257897 INFO (searcherExecutor-2044-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4e0b8438[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.5.0):c1) Uninverting(_2(5.5.0):c1) Uninverting(_3(5.5.0):c1)))}
[junit4] 2> 1257898 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 20
[junit4] 2> 1257899 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[(null)]} 0 0
[junit4] 2> 1257900 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1'
[junit4] 2> 1257900 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1257900 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr
[junit4] 2> 1257900 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 1257900 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 1257944 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1257957 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 1257982 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig-basic.xml
[junit4] 2> 1257986 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=minimal
[junit4] 2> 1257992 WARN (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.s.IndexSchema no uniqueKey specified in schema.
[junit4] 2> 1257995 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1'
[junit4] 2> 1257995 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1
[junit4] 2> 1257995 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/]
[junit4] 2> 1257995 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 1257996 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index/
[junit4] 2> 1257996 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/
[junit4] 2> 1257998 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1258001 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1258002 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1258003 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1258004 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1258005 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/ping,/admin/file,/update/json,/admin/logging,/admin/mbeans,/admin/properties,/update/json/docs,/schema,/admin/luke,/admin/system,/replication,/config,/update/csv,/update,/get,/admin/segments,/admin/threads,/admin/plugins,standard
[junit4] 2> 1258007 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1258007 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1258007 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1258013 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@6d4f382[collection1] main
[junit4] 2> 1258013 WARN (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 1258013 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 1258013 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 1258013 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1258013 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1258014 INFO (searcherExecutor-2048-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6d4f382[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.5.0):c1) Uninverting(_2(5.5.0):c1) Uninverting(_3(5.5.0):c1) Uninverting(_4(5.5.0):c1)))}
[junit4] 2> 1258014 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.DefaultSolrCoreState Closing old IndexWriter... core=collection1
[junit4] 2> 1258026 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index,segFN=segments_4,generation=4}
[junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index,segFN=segments_5,generation=5}
[junit4] 2> 1258026 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 5
[junit4] 2> 1258027 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@2f196c5b
[junit4] 2> 1258030 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index,segFN=segments_5,generation=5}
[junit4] 2> 1258030 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 5
[junit4] 2> 1258031 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1258035 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@4f8cd92b[collection1] main
[junit4] 2> 1258035 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.CoreContainer replacing core: collection1
[junit4] 2> 1258035 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1ce2c8d2
[junit4] 2> 1258035 INFO (searcherExecutor-2048-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4f8cd92b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.5.0):c1) Uninverting(_2(5.5.0):c1) Uninverting(_3(5.5.0):c1) Uninverting(_4(5.5.0):c1)))}
[junit4] 2> 1258035 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=1,adds=1,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=3,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 1258035 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 1258036 INFO (TEST-TestNRTOpen.testReaderIsNRT-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testReaderIsNRT
[junit4] 2> 1258041 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testSharedCores
[junit4] 2> 1258041 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1258041 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 1258043 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[(null)]} 0 0
[junit4] 2> 1258044 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1258060 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index,segFN=segments_5,generation=5}
[junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index,segFN=segments_6,generation=6}
[junit4] 2> 1258060 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 6
[junit4] 2> 1258061 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@48d727c5[collection1] main
[junit4] 2> 1258061 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1258061 INFO (searcherExecutor-2048-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@48d727c5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(5.5.0):c1)))}
[junit4] 2> 1258063 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 18
[junit4] 2> 1258064 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1258064 WARN (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 Starting optimize... Reading and rewriting the entire index! Use with care.
[junit4] 2> 1258087 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index,segFN=segments_6,generation=6}
[junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index,segFN=segments_7,generation=7}
[junit4] 2> 1258087 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 7
[junit4] 2> 1258088 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@7bf25b83[collection1] main
[junit4] 2> 1258089 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1258089 INFO (searcherExecutor-2048-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7bf25b83[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7(5.5.0):c1)))}
[junit4] 2> 1258089 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{optimize=} 0 25
[junit4] 2> 1258091 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[(null)]} 0 0
[junit4] 2> 1258092 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 1258102 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@de27f7b[collection1] main
[junit4] 2> 1258103 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1258103 INFO (searcherExecutor-2048-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@de27f7b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7(5.5.0):c1) Uninverting(_8(5.5.0):c1)))}
[junit4] 2> 1258103 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 10
[junit4] 2> 1258105 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[(null)]} 0 0
[junit4] 2> 1258106 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[(null)]} 0 0
[junit4] 2> 1258107 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1258129 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index,segFN=segments_7,generation=7}
[junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index,segFN=segments_8,generation=8}
[junit4] 2> 1258129 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 8
[junit4] 2> 1258132 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@4932ac43[collection1] main
[junit4] 2> 1258132 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1258132 INFO (searcherExecutor-2048-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4932ac43[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(5.5.0):c2) Uninverting(_a(5.5.0):c2)))}
[junit4] 2> 1258133 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 26
[junit4] 2> 1258135 INFO (TEST-TestNRTOpen.testSharedCores-seed#[BF6E80FB6238D5D5]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testSharedCores
[junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestNRTOpen -Dtests.method=testSharedCores -Dtests.seed=BF6E80FB6238D5D5 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt -Dtests.locale=ar-DZ -Dtests.timezone=Asia/Yerevan -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 0.10s J2 | TestNRTOpen.testSharedCores <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<3> but was:<2>
[junit4] > at __randomizedtesting.SeedInfo.seed([BF6E80FB6238D5D5:596DCAB4EACEF8DA]:0)
[junit4] > at org.apache.solr.core.TestNRTOpen.testSharedCores(TestNRTOpen.java:116)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1258140 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 1258140 INFO (SUITE-TestNRTOpen-seed#[BF6E80FB6238D5D5]-worker) [ x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1773166086
[junit4] 2> 1258141 INFO (coreCloseExecutor-2052-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5d36befe
[junit4] 2> 1258141 INFO (coreCloseExecutor-2052-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommits=0,optimizes=1,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=4,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 1258141 INFO (coreCloseExecutor-2052-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 1258141 INFO (coreCloseExecutor-2052-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1258141 INFO (coreCloseExecutor-2052-thread-1-processing-x:collection1) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 1258151 INFO (coreCloseExecutor-2052-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index,segFN=segments_8,generation=8}
[junit4] 2> commit{dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index,segFN=segments_9,generation=9}
[junit4] 2> 1258151 INFO (coreCloseExecutor-2052-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 9
[junit4] 2> 1258153 INFO (coreCloseExecutor-2052-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 1258153 INFO (coreCloseExecutor-2052-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing StandardDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1258153 INFO (coreCloseExecutor-2052-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index;done=false>>]
[junit4] 2> 1258153 INFO (coreCloseExecutor-2052-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001/index
[junit4] 2> 1258153 INFO (coreCloseExecutor-2052-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001 [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001;done=false>>]
[junit4] 2> 1258153 INFO (coreCloseExecutor-2052-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001/init-core-data-001
[junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J2/temp/solr.core.TestNRTOpen_BF6E80FB6238D5D5-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {foo=FSTOrd50, bazzz=Lucene50(blocksize=128), baz=Lucene50(blocksize=128), foo2=Lucene50(blocksize=128), bazz=Lucene50(blocksize=128)}, docValues:{}, sim=DefaultSimilarity, locale=ar-DZ, timezone=Asia/Yerevan
[junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=4,threads=1,free=176004136,total=528482304
[junit4] 2> NOTE: All tests run in this JVM: [AutoCommitTest, QueryParsingTest, DistributedExpandComponentTest, FullSolrCloudDistribCmdsTest, TestManagedSynonymFilterFactory, TestMacros, TestHdfsUpdateLog, DocValuesMultiTest, TestCharFilters, TestPerFieldSimilarityWithDefaultOverride, TestCopyFieldCollectionResource, TestManagedSchema, TestUseDocValuesAsStored2, TestReRankQParserPlugin, BJQParserTest, VMParamsZkACLAndCredentialsProvidersTest, TestCloudManagedSchemaConcurrent, HdfsNNFailoverTest, CoreAdminHandlerTest, CollectionReloadTest, TestRawResponseWriter, TestExclusionRuleCollectionAccess, TestObjectReleaseTracker, TestFieldTypeCollectionResource, TestShortCircuitedRequests, TestSweetSpotSimilarityFactory, TestSolrQueryParserResource, RuleEngineTest, TestSortingResponseWriter, TestReplicationHandlerBackup, TestPerFieldSimilarity, RecoveryZkTest, TestSchemaVersionResource, TestClusterStateMutator, TestStressRecovery, SharedFSAutoReplicaFailoverTest, TestJmxMonitoredMap, TestCloudInspectUtil, FieldMutatingUpdateProcessorTest, LeaderElectionTest, AtomicUpdatesTest, TestTrieFacet, SuggestComponentTest, DirectUpdateHandlerTest, TestZkChroot, RequestHandlersTest, TestTestInjection, TestNRTOpen]
[junit4] Completed [104/566 (1!)] on J2 in 0.88s, 2 tests, 1 failure <<< FAILURES!
[...truncated 174 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_BF6E80FB6238D5D5-001/init-core-data-001
[junit4] 2> 997288 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestStopPoll
[junit4] 2> 997290 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_BF6E80FB6238D5D5-001/solr-instance-001/collection1
[junit4] 2> 997310 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[BF6E80FB6238D5D5]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 997406 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[BF6E80FB6238D5D5]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@27058c08{/solr,null,AVAILABLE}
[junit4] 2> 997407 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[BF6E80FB6238D5D5]) [ ] o.e.j.s.ServerConnector Started ServerConnector@5d2c753b{HTTP/1.1}{127.0.0.1:54490}
[junit4] 2> 997407 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[BF6E80FB6238D5D5]) [ ] o.e.j.s.Server Started @1000277ms
[junit4] 2> 997407 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=54490, solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_BF6E80FB6238D5D5-001/solr-instance-001/collection1/data}
[junit4] 2> 997407 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
[junit4] 2> 997408 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[BF6E80FB6238D5D5]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.Tes
[...truncated too long message...]
dateRequestHandler.java:94)
[junit4] 2> at org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:69)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:155)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2082)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:670)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:458)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:225)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:183)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:109)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
[junit4] 2> at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83)
[junit4] 2> at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:300)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:499)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> Feb 23, 2016 5:28:10 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene54): {id=Lucene50(blocksize=128), _version_=FST50, rnd_b=Lucene50(blocksize=128), a_i=Lucene50(blocksize=128), a_t=BlockTreeOrds(blocksize=128)}, docValues:{}, sim=DefaultSimilarity, locale=he, timezone=Europe/Tirane
[junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=4,threads=2,free=230449920,total=526909440
[junit4] 2> NOTE: All tests run in this JVM: [AutoCommitTest, QueryParsingTest, DistributedExpandComponentTest, FullSolrCloudDistribCmdsTest, TestManagedSynonymFilterFactory, TestMacros, TestHdfsUpdateLog, DocValuesMultiTest, TestCharFilters, TestPerFieldSimilarityWithDefaultOverride, TestCopyFieldCollectionResource, TestManagedSchema, TestUseDocValuesAsStored2, TestReRankQParserPlugin, BJQParserTest, VMParamsZkACLAndCredentialsProvidersTest, TestCloudManagedSchemaConcurrent, HdfsNNFailoverTest, CoreAdminHandlerTest, CollectionReloadTest, TestRawResponseWriter, TestExclusionRuleCollectionAccess, TestObjectReleaseTracker, TestFieldTypeCollectionResource, TestShortCircuitedRequests, TestSweetSpotSimilarityFactory, TestSolrQueryParserResource, RuleEngineTest, TestSortingResponseWriter, TestReplicationHandlerBackup, TestPerFieldSimilarity, RecoveryZkTest, TestSchemaVersionResource, TestClusterStateMutator, TestStressRecovery, SharedFSAutoReplicaFailoverTest, TestJmxMonitoredMap, TestCloudInspectUtil, FieldMutatingUpdateProcessorTest, LeaderElectionTest, AtomicUpdatesTest, TestTrieFacet, SuggestComponentTest, DirectUpdateHandlerTest, TestZkChroot, RequestHandlersTest, TestTestInjection, TestNRTOpen, ReplicationFactorTest, FieldAnalysisRequestHandlerTest, TestSchemaNameResource, TestMiniSolrCloudClusterSSL, TestSearchPerf, SecurityConfHandlerTest, SimpleFacetsTest, TestCollapseQParserPlugin, TestRecovery, TestLRUStatsCache, TestXIncludeConfig, TestConfigSetProperties, TestReload, TestConfigSetsAPI, SuggesterTSTTest, DeleteLastCustomShardedReplicaTest, TestElisionMultitermQuery, TestImplicitCoreProperties, ForceLeaderTest, CurrencyFieldXmlFileTest, TestSearcherReuse, TestCollationField, TestAuthorizationFramework, TestSort, SSLMigrationTest, TestFastWriter, TestSuggestSpellingConverter, DirectSolrConnectionTest, TestNamedUpdateProcessors, OverseerTaskQueueTest, TestFuzzyAnalyzedSuggestions, TestMinMaxOnMultiValuedField, CursorMarkTest, DirectoryFactoryTest, NoCacheHeaderTest, PluginInfoTest, CollectionsAPIAsyncDistributedZkTest, StatsComponentTest, SolrTestCaseJ4Test, ShowFileRequestHandlerTest, TestIndexingPerformance, TestTolerantSearch, BasicDistributedZk2Test, UnloadDistributedZkTest, OverseerTest, ShardRoutingTest, BasicZkTest, ClusterStateUpdateTest, ZkSolrClientTest, TestRandomDVFaceting, ZkCLITest, ShardRoutingCustomTest, TestDistributedSearch, TestDistributedGrouping, TestHashPartitioner, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, ZkControllerTest, TestRealTimeGet, TestStressReorder, HardAutoCommitTest, TestSolr4Spatial, SpellCheckComponentTest, TestGroupingSearch, SolrCmdDistributorTest, PeerSyncTest, BadIndexSchemaTest, TestFunctionQuery, TestIndexSearcher, HighlighterTest, SoftAutoCommitTest, SolrIndexSplitterTest, TestCoreDiscovery, SignatureUpdateProcessorFactoryTest, TestExtendedDismaxParser, SuggesterFSTTest, TestFoldingMultitermQuery, SuggesterTest, TestStressLucene, SpatialFilterTest, SuggesterWFSTTest, TestCSVLoader, PolyFieldTest, WordBreakSolrSpellCheckerTest, SolrCoreCheckLockOnStartupTest, TestPseudoReturnFields, TestAtomicUpdateErrorCases, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, TestSolrDeletionPolicy1, DebugComponentTest, LukeRequestHandlerTest, TestWriterPerf, TestReversedWildcardFilterFactory, PrimitiveFieldTypeTest, TermsComponentTest, TestValueSourceCache, TestSolrQueryParser, FastVectorHighlighterTest, IndexSchemaRuntimeFieldTest, ReturnFieldsTest, TestCSVResponseWriter, TestAnalyzedSuggestions, JsonLoaderTest, TestPHPSerializedResponseWriter, SearchHandlerTest, TestLFUCache, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort, TestEmbeddedSolrServerConstructors, TestJettySolrRunner, AssignTest, CleanupOldIndexTest, ConnectionManagerTest, DeleteStatusTest, LeaderInitiatedRecoveryOnCommitTest, MigrateRouteKeyTest, OverseerCollectionConfigSetProcessorTest, RestartWhileUpdatingTest, TestConfigSetsAPIZkFailure, TestCryptoKeys, TestDistribDocBasedVersion, TestMiniSolrCloudCluster, TestReplicaProperties, HdfsChaosMonkeyNothingIsSafeTest, HdfsChaosMonkeySafeLeaderTest]
[junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory.
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=HdfsChaosMonkeySafeLeaderTest -Dtests.seed=BF6E80FB6238D5D5 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt -Dtests.locale=he -Dtests.timezone=Europe/Tirane -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J2 | HdfsChaosMonkeySafeLeaderTest (suite) <<<
[junit4] > Throwable #1: java.lang.AssertionError: ObjectTracker found 1 object(s) that were not released!!! [HdfsTransactionLog]
[junit4] > at __randomizedtesting.SeedInfo.seed([BF6E80FB6238D5D5]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.afterClass(SolrTestCaseJ4.java:228)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] Completed [447/566 (3!)] on J2 in 173.29s, 1 test, 1 failure <<< FAILURES!
[...truncated 398 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:757: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:694: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/common-build.xml:534: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/lucene/common-build.xml:1477: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/lucene/common-build.xml:1033: There were test failures: 566 suites (7 ignored), 2275 tests, 2 suite-level errors, 1 failure, 34 ignored (18 assumptions) [seed: BF6E80FB6238D5D5]
Total time: 203 minutes 11 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-NightlyTests-5.5 - Build # 11 - Still Failing
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.5/11/
1 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestReplicateAfterCoreReload
Error Message:
expected:<[{indexVersion=1456331544314,generation=2,filelist=[_2.cfe, _2.cfs, _2.si, _3.cfe, _3.cfs, _3.si, _4.cfe, _4.cfs, _4.si, _5.cfe, _5.cfs, _5.si, _6.cfe, _6.cfs, _6.si, segments_2]}]> but was:<[{indexVersion=1456331544314,generation=2,filelist=[_2.cfe, _2.cfs, _2.si, _3.cfe, _3.cfs, _3.si, _4.cfe, _4.cfs, _4.si, _5.cfe, _5.cfs, _5.si, _6.cfe, _6.cfs, _6.si, segments_2]}, {indexVersion=1456331544314,generation=3,filelist=[_2.cfe, _2.cfs, _2.si, _7.cfe, _7.cfs, _7.si, segments_3]}]>
Stack Trace:
java.lang.AssertionError: expected:<[{indexVersion=1456331544314,generation=2,filelist=[_2.cfe, _2.cfs, _2.si, _3.cfe, _3.cfs, _3.si, _4.cfe, _4.cfs, _4.si, _5.cfe, _5.cfs, _5.si, _6.cfe, _6.cfs, _6.si, segments_2]}]> but was:<[{indexVersion=1456331544314,generation=2,filelist=[_2.cfe, _2.cfs, _2.si, _3.cfe, _3.cfs, _3.si, _4.cfe, _4.cfs, _4.si, _5.cfe, _5.cfs, _5.si, _6.cfe, _6.cfs, _6.si, segments_2]}, {indexVersion=1456331544314,generation=3,filelist=[_2.cfe, _2.cfs, _2.si, _7.cfe, _7.cfs, _7.si, segments_3]}]>
at __randomizedtesting.SeedInfo.seed([423982DAE5CCF9B3:67EE99EA9584F7B0]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:147)
at org.apache.solr.handler.TestReplicationHandler.doTestReplicateAfterCoreReload(TestReplicationHandler.java:1140)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
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:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
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:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11579 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/init-core-data-001
[junit4] 2> 1111879 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestStopPoll
[junit4] 2> 1111879 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001/collection1
[junit4] 2> 1111884 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 1111886 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3f474bdd{/solr,null,AVAILABLE}
[junit4] 2> 1111886 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.e.j.s.ServerConnector Started ServerConnector@61c5ba9f{HTTP/1.1}{127.0.0.1:57282}
[junit4] 2> 1111887 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.e.j.s.Server Started @1114829ms
[junit4] 2> 1111887 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57282, solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001/collection1/data}
[junit4] 2> 1111887 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
[junit4] 2> 1111887 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001'
[junit4] 2> 1111887 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1111887 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1111887 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001/solr.xml
[junit4] 2> 1111902 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001/.
[junit4] 2> 1111902 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.CoreContainer New CoreContainer 833231357
[junit4] 2> 1111902 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001]
[junit4] 2> 1111902 WARN (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001/lib
[junit4] 2> 1111902 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 1111904 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1111904 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1111904 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1111904 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1111904 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1111905 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001/.
[junit4] 2> 1111906 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {schema=schema.xml, loadOnStartup=true, transient=false, name=collection1, configSetProperties=configsetprops.json, config=solrconfig.xml, dataDir=data/}
[junit4] 2> 1111906 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001/./collection1
[junit4] 2> 1111906 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 1111907 INFO (coreLoadExecutor-2294-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001/collection1'
[junit4] 2> 1111908 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0
[junit4] 2> 1111908 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1111908 INFO (coreLoadExecutor-2294-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1111908 INFO (coreLoadExecutor-2294-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1111917 INFO (coreLoadExecutor-2294-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1111931 INFO (coreLoadExecutor-2294-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 1111958 INFO (coreLoadExecutor-2294-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1111962 INFO (coreLoadExecutor-2294-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1111971 INFO (coreLoadExecutor-2294-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1111973 INFO (coreLoadExecutor-2294-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001/collection1'
[junit4] 2> 1111973 INFO (coreLoadExecutor-2294-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001/./collection1
[junit4] 2> 1111974 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1111974 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001/./collection1/data/]
[junit4] 2> 1111974 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 1111975 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001/./collection1/data
[junit4] 2> 1111975 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001/./collection1/data/index/
[junit4] 2> 1111975 WARN (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1111975 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001/./collection1/data/index
[junit4] 2> 1111976 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=1.13671875, floorSegmentMB=1.8857421875, forceMergeDeletesPctAllowed=0.5062597888723119, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1111977 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RawDirectoryWrapper(NIOFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/index-NIOFSDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@19ed6877),segFN=segments_1,generation=1}
[junit4] 2> 1111977 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1111980 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1111983 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1111984 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1111985 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1111986 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1111988 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001/./collection1/data/
[junit4] 2> 1111995 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 1111995 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /update,/schema,/admin/segments,defaults,/admin/luke,/config,/update/json/docs,/update/json,/admin/plugins,/admin/properties,/replication,/admin/mbeans,/admin/system,/admin/ping,/admin/file,lazy,/get,/update/csv,/admin/threads,/admin/logging,standard
[junit4] 2> 1111997 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1111997 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1111997 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1111997 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@6b1e169b
[junit4] 2> 1111998 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RawDirectoryWrapper(NIOFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/index-NIOFSDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@19ed6877),segFN=segments_1,generation=1}
[junit4] 2> 1111999 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1111999 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@61d7f889[collection1] main
[junit4] 2> 1111999 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001/collection1/conf
[junit4] 2> 1111999 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001/collection1/conf}
[junit4] 2> 1111999 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-001/collection1/conf
[junit4] 2> 1111999 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1112000 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1112000 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1112000 INFO (searcherExecutor-2295-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@61d7f889[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1112000 INFO (coreLoadExecutor-2294-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1112410 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002/collection1
[junit4] 2> 1112414 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 1112417 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@30bc1f5b{/solr,null,AVAILABLE}
[junit4] 2> 1112417 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.e.j.s.ServerConnector Started ServerConnector@6caf378b{HTTP/1.1}{127.0.0.1:32818}
[junit4] 2> 1112417 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.e.j.s.Server Started @1115360ms
[junit4] 2> 1112417 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostPort=32818, solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002/collection1/data, hostContext=/solr}
[junit4] 2> 1112417 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6
[junit4] 2> 1112418 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002'
[junit4] 2> 1112418 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1112418 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1112418 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002/solr.xml
[junit4] 2> 1112435 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002/.
[junit4] 2> 1112435 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.CoreContainer New CoreContainer 1583889198
[junit4] 2> 1112435 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002]
[junit4] 2> 1112435 WARN (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002/lib
[junit4] 2> 1112436 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 1112437 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1112438 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1112438 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1112438 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1112438 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1112440 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002/.
[junit4] 2> 1112441 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {config=solrconfig.xml, transient=false, name=collection1, dataDir=data/, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json}
[junit4] 2> 1112441 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002/./collection1
[junit4] 2> 1112441 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions
[junit4] 2> 1112441 INFO (coreLoadExecutor-2304-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002/collection1'
[junit4] 2> 1112444 INFO (coreLoadExecutor-2304-thread-1) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1112444 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0
[junit4] 2> 1112444 INFO (coreLoadExecutor-2304-thread-1) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1112444 INFO (TEST-TestReplicationHandler.doTestStopPoll-seed#[423982DAE5CCF9B3]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1112451 INFO (coreLoadExecutor-2304-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1112466 INFO (coreLoadExecutor-2304-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
[junit4] 2> 1112515 INFO (coreLoadExecutor-2304-thread-1) [ ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1112520 INFO (coreLoadExecutor-2304-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1112528 INFO (coreLoadExecutor-2304-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1112530 INFO (coreLoadExecutor-2304-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002/collection1'
[junit4] 2> 1112530 INFO (coreLoadExecutor-2304-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002/./collection1
[junit4] 2> 1112530 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1112531 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002/./collection1/data/]
[junit4] 2> 1112531 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 1112531 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002/./collection1/data
[junit4] 2> 1112531 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002/./collection1/data/index/
[junit4] 2> 1112531 WARN (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002/./collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1112532 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002/./collection1/data/index
[junit4] 2> 1112532 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=1.13671875, floorSegmentMB=1.8857421875, forceMergeDeletesPctAllowed=0.5062597888723119, segmentsPerTier=18.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 1112534 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RawDirectoryWrapper(NIOFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/index-NIOFSDirectory-002 lockFactory=org.apache.lucene.store.NativeFSLockFactory@19ed6877),segFN=segments_1,generation=1}
[junit4] 2> 1112534 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1112534 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002/./collection1/data/
[junit4] 2> 1112536 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1112539 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1112540 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1112541 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1112542 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1112543 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 1112544 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /update,/schema,/admin/segments,defaults,/admin/luke,/config,/update/json/docs,/update/json,/admin/plugins,/admin/properties,/replication,/admin/mbeans,/admin/system,/admin/ping,/admin/file,lazy,/get,/update/csv,/admin/threads,/admin/logging,standard
[junit4] 2> 1112545 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1112545 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1112545 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1112545 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@30ffed
[junit4] 2> 1112547 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RawDirectoryWrapper(NIOFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/index-NIOFSDirectory-002 lockFactory=org.apache.lucene.store.NativeFSLockFactory@19ed6877),segFN=segments_1,generation=1}
[junit4] 2> 1112547 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 1112547 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@4bd07dc3[collection1] main
[junit4] 2> 1112547 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002/collection1/conf
[junit4] 2> 1112547 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002/collection1/conf}
[junit4] 2> 1112547 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-002/collection1/conf
[junit4] 2> 1112548 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1112551 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 1112551 INFO (searcherExecutor-2305-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4bd07dc3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1112551 INFO (coreLoadExecutor-2304-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1112554 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&version=2&qt=/replication&command=indexversion} status=0 QTime=0
[junit4] 2> 1112948 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={wt=javabin&version=2&q=*:*&sort=id+desc} hits=0 status=0 QTime=0
[junit4] 2> 1112953 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 2
[junit4] 2> 1112955 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 1112957 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 1112959 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 1112960 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 1112962 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0
[junit4] 2> 1112964 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6]} 0 0
[junit4] 2> 1112966 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7]} 0 0
[junit4] 2> 1112968 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8]} 0 0
[junit4] 2> 1112969 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9]} 0 0
[junit4] 2> 1112971 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 0
[junit4] 2> 1112973 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11]} 0 0
[junit4] 2> 1112978 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12]} 0 0
[junit4] 2> 1112980 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[13]} 0 0
[junit4] 2> 1112982 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[14]} 0 0
[junit4] 2> 1112984 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[15]} 0 0
[junit4] 2> 1112986 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16]} 0 0
[junit4] 2> 1112988 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17]} 0 0
[junit4] 2> 1112989 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[18]} 0 0
[junit4] 2> 1112991 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[19]} 0 0
[junit4] 2> 1112993 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20]} 0 0
[junit4] 2> 1112995 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[21]} 0 0
[junit4] 2> 1112997 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[22]} 0 0
[junit4] 2> 1112998 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[23]} 0 0
[junit4] 2> 1113000 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[24]} 0 0
[junit4] 2> 1113002 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[25]} 0 0
[junit4] 2> 1113004 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[26]} 0 0
[junit4] 2> 1113005 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[27]} 0 0
[junit4] 2> 1113007 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[28]} 0 0
[junit4] 2> 1113009 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[29]} 0 0
[junit4] 2> 1113011 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[30]} 0 0
[junit4] 2> 1113013 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[31]} 0 0
[junit4] 2> 1113015 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[32]} 0 0
[junit4] 2> 1113016 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[33]} 0 0
[junit4] 2> 1113018 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[34]} 0 0
[junit4] 2> 1113020 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[35]} 0 0
[junit4] 2> 1113022 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[36]} 0 0
[junit4] 2> 1113024 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[37]} 0 0
[junit4] 2> 1113025 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[38]} 0 0
[junit4] 2> 1113027 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[39]} 0 0
[junit4] 2> 1113029 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[40]} 0 0
[junit4] 2> 1113031 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[41]} 0 0
[junit4] 2> 1113032 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[42]} 0 0
[junit4] 2> 1113034 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[43]} 0 0
[junit4] 2> 1113036 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[44]} 0 0
[junit4] 2> 1113038 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[45]} 0 0
[junit4] 2> 1113040 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[46]} 0 0
[junit4] 2> 1113042 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[47]} 0 0
[junit4] 2> 1113044 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[48]} 0 0
[junit4] 2> 1113045 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[49]} 0 0
[junit4] 2> 1113047 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[50]} 0 0
[junit4] 2> 1113049 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[51]} 0 0
[junit4] 2> 1113051 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[52]} 0 0
[junit4] 2> 1113053 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[53]} 0 0
[junit4] 2> 1113054 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[54]} 0 0
[junit4] 2> 1113056 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[55]} 0 0
[junit4] 2> 1113058 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[56]} 0 0
[junit4] 2> 1113060 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[57]} 0 0
[junit4] 2> 1113061 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[58]} 0 0
[junit4] 2> 1113063 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[59]} 0 0
[junit4] 2> 1113065 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[60]} 0 0
[junit4] 2> 1113067 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[61]} 0 0
[junit4] 2> 1113069 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[62]} 0 0
[junit4] 2> 1113070 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[63]} 0 0
[junit4] 2> 1113072 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[64]} 0 0
[junit4] 2> 1113074 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[65]} 0 0
[junit4] 2> 1113076 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[66]} 0 0
[junit4] 2> 1113078 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[67]} 0 0
[junit4] 2> 1113080 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[68]} 0 0
[junit4] 2> 1113082 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[69]} 0 0
[junit4] 2> 1113083 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[70]} 0 0
[junit4] 2> 1113085 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[71]} 0 0
[junit4] 2> 1113087 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[72]} 0 0
[junit4] 2> 1113089 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[73]} 0 0
[junit4] 2> 1113090 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[74]} 0 0
[junit4] 2> 1113092 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[75]} 0 0
[junit4] 2> 1113094 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[76]} 0 0
[junit4] 2> 1113096 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[77]} 0 0
[junit4] 2> 1113098 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[78]} 0 0
[junit4] 2> 1113099 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[79]} 0 0
[junit4] 2> 1113101 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[80]} 0 0
[junit4] 2> 1113103 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[81]} 0 0
[junit4] 2> 1113105 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[82]} 0 0
[junit4] 2> 1113107 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[83]} 0 0
[junit4] 2> 1113109 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[84]} 0 0
[junit4] 2> 1113110 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[85]} 0 0
[junit4] 2> 1113112 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[86]} 0 0
[junit4] 2> 1113114 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[87]} 0 0
[junit4] 2> 1113116 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[88]} 0 0
[junit4] 2> 1113118 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[89]} 0 0
[junit4] 2> 1113119 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[90]} 0 0
[junit4] 2> 1113121 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[91]} 0 0
[junit4] 2> 1113123 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[92]} 0 0
[junit4] 2> 1113125 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[93]} 0 0
[junit4] 2> 1113127 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[94]} 0 0
[junit4] 2> 1113129 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[95]} 0 0
[junit4] 2> 1113131 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[96]} 0 0
[junit4] 2> 1113133 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[97]} 0 0
[junit4] 2> 1113134 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[98]} 0 0
[junit4] 2> 1113136 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[99]} 0 0
[junit4] 2> 1113138 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[100]} 0 0
[junit4] 2> 1113140 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[101]} 0 0
[junit4] 2> 1113141 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[102]} 0 0
[junit4] 2> 1113143 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[103]} 0 0
[junit4] 2> 1113145 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[104]} 0 0
[junit4] 2> 1113147 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[105]} 0 0
[junit4] 2> 1113149 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[106]} 0 0
[junit4] 2> 1113150 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[107]} 0 0
[junit4] 2> 1113152 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[108]} 0 0
[junit4] 2> 1113154 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[109]} 0 0
[junit4] 2> 1113156 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[110]} 0 0
[junit4] 2> 1113157 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[111]} 0 0
[junit4] 2> 1113159 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[112]} 0 0
[junit4] 2> 1113161 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[113]} 0 0
[junit4] 2> 1113163 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[114]} 0 0
[junit4] 2> 1113164 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[115]} 0 0
[junit4] 2> 1113166 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[116]} 0 0
[junit4] 2> 1113168 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[117]} 0 0
[junit4] 2> 1113170 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[118]} 0 0
[junit4] 2> 1113172 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[119]} 0 0
[junit4] 2> 1113174 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[120]} 0 0
[junit4] 2> 1113176 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[121]} 0 0
[junit4] 2> 1113178 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[122]} 0 0
[junit4] 2> 1113179 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[123]} 0 0
[junit4] 2> 1113181 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[124]} 0 0
[junit4] 2> 1113183 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[125]} 0 0
[junit4] 2> 1113185 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[126]} 0 0
[junit4] 2> 1113186 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[127]} 0 0
[junit4] 2> 1113188 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[128]} 0 0
[junit4] 2> 1113190 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[129]} 0 0
[junit4] 2> 1113193 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[130]} 0 0
[junit4] 2> 1113195 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[131]} 0 0
[junit4] 2> 1113197 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[132]} 0 0
[junit4] 2> 1113198 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[133]} 0 0
[junit4] 2> 1113200 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[134]} 0 0
[junit4] 2> 1113202 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[135]} 0 0
[junit4] 2> 1113204 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[136]} 0 0
[junit4] 2> 1113205 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[137]} 0 0
[junit4] 2> 1113207 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[138]} 0 0
[junit4] 2> 1113209 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[139]} 0 0
[junit4] 2> 1113211 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[140]} 0 0
[junit4] 2> 1113212 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[141]} 0 0
[junit4] 2> 1113214 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[142]} 0 0
[junit4] 2> 1113216 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[143]} 0 0
[junit4] 2> 1113218 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[144]} 0 0
[junit4] 2> 1113219 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[145]} 0 0
[junit4] 2> 1113221 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[146]} 0 0
[junit4] 2> 1113223 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[147]} 0 0
[junit4] 2> 1113225 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[148]} 0 0
[junit4] 2> 1113227 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[149]} 0 0
[junit4] 2> 1113228 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[150]} 0 0
[junit4] 2> 1113230 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[151]} 0 0
[junit4] 2> 1113232 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[152]} 0 0
[junit4] 2> 1113234 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[153]} 0 0
[junit4] 2> 1113235 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[154]} 0 0
[junit4] 2> 1113237 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[155]} 0 0
[junit4] 2> 1113239 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[156]} 0 0
[junit4] 2> 1113241 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[157]} 0 0
[junit4] 2> 1113242 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[158]} 0 0
[junit4] 2> 1113244 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[159]} 0 0
[junit4] 2> 1113246 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[160]} 0 0
[junit4] 2> 1113248 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[161]} 0 0
[junit4] 2> 1113250 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[162]} 0 0
[junit4] 2> 1113252 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[163]} 0 0
[junit4] 2> 1113254 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[164]} 0 0
[junit4] 2> 1113255 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[165]} 0 0
[junit4] 2> 1113257 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[166]} 0 0
[junit4] 2> 1113259 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[167]} 0 0
[junit4] 2> 1113261 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[168]} 0 0
[junit4] 2> 1113263 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[169]} 0 0
[junit4] 2> 1113264 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[170]} 0 0
[junit4] 2> 1113266 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[171]} 0 0
[junit4] 2> 1113268 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[172]} 0 0
[junit4] 2> 1113270 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[173]} 0 0
[junit4] 2> 1113271 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[174]} 0 0
[junit4] 2> 1113273 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[175]} 0 0
[junit4] 2> 1113278 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[176]} 0 0
[junit4] 2> 1113280 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[177]} 0 0
[junit4] 2> 1113282 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[178]} 0 0
[junit4] 2> 1113284 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[179]} 0 0
[junit4] 2> 1113286 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[180]} 0 0
[junit4] 2> 1113288 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[181]} 0 0
[junit4] 2> 1113290 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[182]} 0 0
[junit4] 2> 1113293 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[183]} 0 0
[junit4] 2> 1113295 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[184]} 0 0
[junit4] 2> 1113296 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[185]} 0 0
[junit4] 2> 1113298 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[186]} 0 0
[junit4] 2> 1113300 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[187]} 0 0
[junit4] 2> 1113302 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[188]} 0 0
[junit4] 2> 1113304 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[189]} 0 0
[junit4] 2> 1113305 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[190]} 0 0
[junit4] 2> 1113307 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[191]} 0 0
[junit4] 2> 1113309 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[192]} 0 0
[junit4] 2> 1113311 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[193]} 0 0
[junit4] 2> 1113313 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[194]} 0 0
[junit4] 2> 1113315 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[195]} 0 0
[junit4] 2> 1113317 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[196]} 0 0
[junit4] 2> 1113319 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[197]} 0 0
[junit4] 2> 1113321 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[198]} 0 0
[junit4] 2> 1113323 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[199]} 0 0
[junit4] 2> 1113324 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[200]} 0 0
[junit4] 2> 1113326 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[201]} 0 0
[junit4] 2> 1113328 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[202]} 0 0
[junit4] 2> 1113330 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[203]} 0 0
[junit4] 2> 1113332 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[204]} 0 0
[junit4] 2> 1113334 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[205]} 0 0
[junit4] 2> 1113338 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[206]} 0 0
[junit4] 2> 1113340 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[207]} 0 0
[junit4] 2> 1113341 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[208]} 0 0
[junit4] 2> 1113343 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[209]} 0 0
[junit4] 2> 1113345 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[210]} 0 0
[junit4] 2> 1113347 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[211]} 0 0
[junit4] 2> 1113348 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[212]} 0 0
[junit4] 2> 1113350 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[213]} 0 0
[junit4] 2> 1113352 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[214]} 0 0
[junit4] 2> 1113354 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[215]} 0 0
[junit4] 2> 1113356 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[216]} 0 0
[junit4] 2> 1113359 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[217]} 0 0
[junit4] 2> 1113361 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[218]} 0 0
[junit4] 2> 1113362 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[219]} 0 0
[junit4] 2> 1113364 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[220]} 0 0
[junit4] 2> 1113366 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[221]} 0 0
[junit4] 2> 1113368 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[222]} 0 0
[junit4] 2> 1113369 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[223]} 0 0
[junit4] 2> 1113371 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[224]} 0 0
[junit4] 2> 1113373 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[225]} 0 0
[junit4] 2> 1113375 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[226]} 0 0
[junit4] 2> 1113377 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[227]} 0 0
[junit4] 2> 1113379 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[228]} 0 0
[junit4] 2> 1113380 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[229]} 0 0
[junit4] 2> 1113382 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[230]} 0 0
[junit4] 2> 1113384 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[231]} 0 0
[junit4] 2> 1113386 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[232]} 0 0
[junit4] 2> 1113387 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[233]} 0 0
[junit4] 2> 1113390 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[234]} 0 0
[junit4] 2> 1113392 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[235]} 0 0
[junit4] 2> 1113394 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[236]} 0 0
[junit4] 2> 1113395 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[237]} 0 0
[junit4] 2> 1113397 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[238]} 0 0
[junit4] 2> 1113399 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[239]} 0 0
[junit4] 2> 1113400 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[240]} 0 0
[junit4] 2> 1113402 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[241]} 0 0
[junit4] 2> 1113404 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[242]} 0 0
[junit4] 2> 1113406 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[243]} 0 0
[junit4] 2> 1113407 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[244]} 0 0
[junit4] 2> 1113409 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[245]} 0 0
[junit4] 2> 1113411 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[246]} 0 0
[junit4] 2> 1113413 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[247]} 0 0
[junit4] 2> 1113414 INFO (qtp1426550687-60711) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[248]} 0 0
[junit4] 2> 1113416 INFO (qtp1426550687-60712) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[249]} 0 0
[junit4] 2> 1113418 INFO (qtp1426550687-60713) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[250]} 0 0
[junit4] 2> 1113420 INFO (qtp1426550687-60714) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[251]} 0 0
[junit4] 2> 1113422 INFO (qtp1426550687-60715) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[252]} 0 0
[junit4] 2>
[...truncated too long message...]
2> 1764115 INFO (coreCloseExecutor-3110-thread-1) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 1764115 INFO (coreCloseExecutor-3110-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1764115 INFO (coreCloseExecutor-3110-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 1764116 INFO (coreCloseExecutor-3110-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 1764117 INFO (coreCloseExecutor-3110-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing NRTCachingDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1764117 INFO (coreCloseExecutor-3110-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-027/collection1/data [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-027/collection1/data;done=false>>]
[junit4] 2> 1764117 INFO (coreCloseExecutor-3110-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-027/collection1/data
[junit4] 2> 1764117 INFO (coreCloseExecutor-3110-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-027/collection1/data/index [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-027/collection1/data/index;done=false>>]
[junit4] 2> 1764117 INFO (coreCloseExecutor-3110-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-027/collection1/data/index
[junit4] 2> 1764118 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[423982DAE5CCF9B3]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3888c7eb{/solr,null,UNAVAILABLE}
[junit4] 2> 1764126 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[423982DAE5CCF9B3]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@7a83947e{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 1764127 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[423982DAE5CCF9B3]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1364716456
[junit4] 2> 1764127 INFO (coreCloseExecutor-3111-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4be0b9ed
[junit4] 2> 1764127 INFO (coreCloseExecutor-3111-thread-1) [ x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 1764127 INFO (coreCloseExecutor-3111-thread-1) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 1764127 INFO (coreCloseExecutor-3111-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1764128 INFO (coreCloseExecutor-3111-thread-1) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 1764130 INFO (coreCloseExecutor-3111-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 1764130 INFO (coreCloseExecutor-3111-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing NRTCachingDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1764131 INFO (coreCloseExecutor-3111-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-028/collection1/data/index.20160225043720752 [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-028/collection1/data/index.20160225043720752;done=false>>]
[junit4] 2> 1764131 INFO (coreCloseExecutor-3111-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-028/collection1/data/index.20160225043720752
[junit4] 2> 1764131 INFO (coreCloseExecutor-3111-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-028/collection1/data [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-028/collection1/data;done=false>>]
[junit4] 2> 1764131 INFO (coreCloseExecutor-3111-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001/solr-instance-028/collection1/data
[junit4] 2> 1764131 INFO (TEST-TestReplicationHandler.doTestStressReplication-seed#[423982DAE5CCF9B3]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3522b892{/solr,null,UNAVAILABLE}
[junit4] 2> 1764133 INFO (SUITE-TestReplicationHandler-seed#[423982DAE5CCF9B3]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build/solr-core/test/J0/temp/solr.handler.TestReplicationHandler_423982DAE5CCF9B3-001
[junit4] 2> NOTE: test params are: codec=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=22659, maxDocsPerChunk=907, blockSize=416), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=22659, blockSize=416)), sim=RandomSimilarity(queryNorm=false,coord=crazy): {}, locale=en-IE, timezone=Pacific/Tarawa
[junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=4,threads=1,free=47004888,total=509607936
[junit4] 2> NOTE: All tests run in this JVM: [SystemInfoHandlerTest, SaslZkACLProviderTest, TestSolrDynamicMBean, JsonLoaderTest, TestLRUCache, BasicDistributedZkTest, DateFieldTest, ReturnFieldsTest, LeaderInitiatedRecoveryOnShardRestartTest, TestMiniSolrCloudClusterBase, SpatialHeatmapFacetsTest, TestReloadAndDeleteDocs, BigEndianAscendingWordDeserializerTest, TestLMDirichletSimilarityFactory, ZkStateReaderTest, TestRandomFaceting, BadCopyFieldTest, TestArbitraryIndexDir, TestHashQParserPlugin, TestStandardQParsers, TestCSVLoader, TestBlobHandler, TestSimpleQParserPlugin, HdfsChaosMonkeySafeLeaderTest, TestLazyCores, TestAtomicUpdateErrorCases, SolrXmlInZkTest, TestDocSet, TestRandomRequestDistribution, ActionThrottleTest, TestSchemaManager, TestDistributedSearch, DistributedFacetPivotSmallTest, DistributedSpellCheckComponentTest, TestBinaryField, VersionInfoTest, CustomCollectionTest, TestFieldCollectionResource, TestPseudoReturnFields, TestComplexPhraseQParserPlugin, TestReplicationHandler]
[junit4] Completed [179/566 (1!)] on J0 in 652.28s, 13 tests, 1 failure <<< FAILURES!
[...truncated 1241 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:757: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:694: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/solr/common-build.xml:534: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/lucene/common-build.xml:1477: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.5/lucene/common-build.xml:1033: There were test failures: 566 suites (7 ignored), 2275 tests, 1 failure, 34 ignored (18 assumptions) [seed: 423982DAE5CCF9B3]
Total time: 188 minutes 39 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
No prior successful build to compare, so performing full copy of artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any