You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2015/07/23 22:29:04 UTC
[JENKINS] Lucene-Solr-5.x-Windows (32bit/jdk1.7.0_80) - Build #
4933 - Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Windows/4933/
Java: 32bit/jdk1.7.0_80 -client -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication
Error Message:
[index.20150723140346214, index.20150723140347729, index.properties, replication.properties] expected:<1> but was:<2>
Stack Trace:
java.lang.AssertionError: [index.20150723140346214, index.20150723140347729, index.properties, replication.properties] expected:<1> but was:<2>
at __randomizedtesting.SeedInfo.seed([56BD2D639E8ACD3E:8D162DA59BA2A48D]: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.apache.solr.handler.TestReplicationHandler.checkForSingleIndex(TestReplicationHandler.java:818)
at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:785)
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:1627)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
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:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 21423 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\init-core-data-001
[junit4] 2> 1815441 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestRepeater
[junit4] 2> 1815443 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\collection1
[junit4] 2> 1815462 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529
[junit4] 2> 1815467 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1a7464c{/solr,null,AVAILABLE}
[junit4] 2> 1815468 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.e.j.s.ServerConnector Started ServerConnector@f85ecb{HTTP/1.1}{127.0.0.1:60025}
[junit4] 2> 1815469 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.e.j.s.Server Started @1820445ms
[junit4] 2> 1815469 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostPort=60025, hostContext=/solr, solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\collection1\data}
[junit4] 2> 1815469 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@a5c9f1
[junit4] 2> 1815470 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\'
[junit4] 2> 1815524 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\solr.xml
[junit4] 2> 1815565 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\.
[junit4] 2> 1815566 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoreContainer New CoreContainer 32592234
[junit4] 2> 1815566 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\]
[junit4] 2> 1815566 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoreContainer loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\lib
[junit4] 2> 1815567 WARN (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\lib).
[junit4] 2> 1815577 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] 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> 1815580 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1815581 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1815581 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1815581 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1815584 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoresLocator Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\.
[junit4] 2> 1815587 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.SolrCore Created CoreDescriptor: {instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\.\collection1, name=collection1, config=solrconfig.xml, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\.\collection1\, schema=schema.xml, transient=false, loadOnStartup=true, configSetProperties=configsetprops.json, dataDir=data\}
[junit4] 2> 1815588 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoresLocator Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\.\collection1\
[junit4] 2> 1815590 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoresLocator Found 1 core definitions
[junit4] 2> 1815592 INFO (coreLoadExecutor-3760-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\.\collection1\'
[junit4] 2> 1815594 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1815594 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1815637 INFO (coreLoadExecutor-3760-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1815653 INFO (coreLoadExecutor-3760-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0
[junit4] 2> 1815692 INFO (coreLoadExecutor-3760-thread-1) [ ] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1815692 INFO (coreLoadExecutor-3760-thread-1) [ ] o.a.s.s.IndexSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\.\collection1\conf\schema.xml
[junit4] 2> 1815697 INFO (coreLoadExecutor-3760-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1815713 INFO (coreLoadExecutor-3760-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1815718 INFO (coreLoadExecutor-3760-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\.\collection1\conf'
[junit4] 2> 1815718 INFO (coreLoadExecutor-3760-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\.\collection1\
[junit4] 2> 1815719 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1815719 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\.\collection1\], dataDir=[null]
[junit4] 2> 1815719 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 1815726 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\.\collection1\data\
[junit4] 2> 1815729 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\.\collection1\data\index/
[junit4] 2> 1815729 WARN (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\.\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1815729 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\.\collection1\data\index
[junit4] 2> 1815731 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=96.6650390625, floorSegmentMB=1.732421875, forceMergeDeletesPctAllowed=11.479313264183242, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3057751205348932
[junit4] 2> 1815732 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@8f67d1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e8ed81),segFN=segments_1,generation=1}
[junit4] 2> 1815732 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 1815733 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\.\collection1\data\
[junit4] 2> 1815736 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1815745 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1815747 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1815749 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1815753 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1815771 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 1815771 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/segments,/admin/plugins,standard,/replication,/admin/mbeans,/update/csv,/admin/logging,/config,/update/json/docs,/schema,/admin/ping,/admin/properties,lazy,/admin/system,/admin/luke,/admin/threads,/get,/admin/file,defaults,/update,/update/json
[junit4] 2> 1815774 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1815775 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
[junit4] 2> 1815775 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
[junit4] 2> 1815776 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=15, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6135612017968246]
[junit4] 2> 1815777 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@8f67d1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e8ed81),segFN=segments_1,generation=1}
[junit4] 2> 1815777 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 1815777 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@114ecea[collection1] main
[junit4] 2> 1815778 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\.\collection1\conf
[junit4] 2> 1815778 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\.\collection1\conf}
[junit4] 2> 1815778 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-001\.\collection1\conf
[junit4] 2> 1815779 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1815779 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1815779 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1815780 INFO (searcherExecutor-3761-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@114ecea[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1815780 INFO (coreLoadExecutor-3760-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1816136 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\collection1
[junit4] 2> 1816151 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529
[junit4] 2> 1816155 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@13fad12{/solr,null,AVAILABLE}
[junit4] 2> 1816158 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.e.j.s.ServerConnector Started ServerConnector@17b7d7c{HTTP/1.1}{127.0.0.1:60028}
[junit4] 2> 1816158 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.e.j.s.Server Started @1821134ms
[junit4] 2> 1816158 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\collection1\data, hostContext=/solr, hostPort=60028}
[junit4] 2> 1816159 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@a5c9f1
[junit4] 2> 1816159 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\'
[junit4] 2> 1816213 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\solr.xml
[junit4] 2> 1816288 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.
[junit4] 2> 1816290 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoreContainer New CoreContainer 28571482
[junit4] 2> 1816290 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\]
[junit4] 2> 1816290 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoreContainer loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\lib
[junit4] 2> 1816290 WARN (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\lib).
[junit4] 2> 1816300 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] 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> 1816303 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1816304 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1816304 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1816304 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1816305 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoresLocator Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.
[junit4] 2> 1816308 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.SolrCore Created CoreDescriptor: {schema=schema.xml, loadOnStartup=true, transient=false, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\, config=solrconfig.xml, dataDir=data\, configSetProperties=configsetprops.json, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1, name=collection1}
[junit4] 2> 1816308 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoresLocator Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\
[junit4] 2> 1816310 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoresLocator Found 1 core definitions
[junit4] 2> 1816311 INFO (coreLoadExecutor-3770-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\'
[junit4] 2> 1816312 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1816312 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1816361 INFO (coreLoadExecutor-3770-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1816391 INFO (coreLoadExecutor-3770-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0
[junit4] 2> 1816434 INFO (coreLoadExecutor-3770-thread-1) [ ] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1816435 INFO (coreLoadExecutor-3770-thread-1) [ ] o.a.s.s.IndexSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\conf\schema.xml
[junit4] 2> 1816440 INFO (coreLoadExecutor-3770-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1816454 INFO (coreLoadExecutor-3770-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1816458 INFO (coreLoadExecutor-3770-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\conf'
[junit4] 2> 1816458 INFO (coreLoadExecutor-3770-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\
[junit4] 2> 1816458 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1816459 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\], dataDir=[null]
[junit4] 2> 1816459 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 1816461 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\data\
[junit4] 2> 1816462 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\data\index/
[junit4] 2> 1816462 WARN (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1816462 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\data\index
[junit4] 2> 1816463 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=96.6650390625, floorSegmentMB=1.732421875, forceMergeDeletesPctAllowed=11.479313264183242, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3057751205348932
[junit4] 2> 1816463 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1bbafbc lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10e8368),segFN=segments_1,generation=1}
[junit4] 2> 1816463 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 1816464 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\data\
[junit4] 2> 1816469 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1816477 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1816479 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1816481 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1816482 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1816485 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 1816485 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/segments,/admin/plugins,standard,/replication,/admin/mbeans,/update/csv,/admin/logging,/config,/update/json/docs,/schema,/admin/ping,/admin/properties,lazy,/admin/system,/admin/luke,/admin/threads,/get,/admin/file,defaults,/update,/update/json
[junit4] 2> 1816487 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1816489 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
[junit4] 2> 1816489 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
[junit4] 2> 1816489 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=15, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6135612017968246]
[junit4] 2> 1816490 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1bbafbc lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@10e8368),segFN=segments_1,generation=1}
[junit4] 2> 1816490 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 1816490 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@65dfaa[collection1] main
[junit4] 2> 1816490 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\conf
[junit4] 2> 1816490 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\conf}
[junit4] 2> 1816491 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\conf
[junit4] 2> 1816491 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1816495 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll Scheduled at an interval of 1000ms
[junit4] 2> 1816495 INFO (searcherExecutor-3771-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@65dfaa[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1816495 INFO (coreLoadExecutor-3770-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1816854 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@17b7d7c{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 1816854 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=28571482
[junit4] 2> 1816854 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@2ab62
[junit4] 2> 1816855 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.a.s.u.UpdateHandler 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> 1816855 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 1816855 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1816855 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 1816857 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 1816857 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1816857 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\data\index;done=false>>]
[junit4] 2> 1816857 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\data\index
[junit4] 2> 1816858 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\data\;done=false>>]
[junit4] 2> 1816858 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\data\
[junit4] 2> 1816859 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@13fad12{/solr,null,UNAVAILABLE}
[junit4] 2> 1816863 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529
[junit4] 2> 1816868 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@14375f8{/solr,null,AVAILABLE}
[junit4] 2> 1816869 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1f6198a{HTTP/1.1}{127.0.0.1:60031}
[junit4] 2> 1816869 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.e.j.s.Server Started @1821845ms
[junit4] 2> 1816869 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60031, solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\collection1\data}
[junit4] 2> 1816869 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@a5c9f1
[junit4] 2> 1816869 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\'
[junit4] 2> 1816902 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\solr.xml
[junit4] 2> 1816931 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.
[junit4] 2> 1816932 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoreContainer New CoreContainer 2719051
[junit4] 2> 1816932 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\]
[junit4] 2> 1816932 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoreContainer loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\lib
[junit4] 2> 1816932 WARN (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\lib).
[junit4] 2> 1816942 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] 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> 1816945 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1816945 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1816945 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1816946 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1816948 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoresLocator Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.
[junit4] 2> 1816949 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.SolrCore Created CoreDescriptor: {absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\, transient=false, schema=schema.xml, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1, name=collection1, loadOnStartup=true, configSetProperties=configsetprops.json, dataDir=data\, config=solrconfig.xml}
[junit4] 2> 1816949 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoresLocator Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\
[junit4] 2> 1816950 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoresLocator Found 1 core definitions
[junit4] 2> 1816951 INFO (coreLoadExecutor-3781-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\'
[junit4] 2> 1816952 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1816953 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1816991 INFO (coreLoadExecutor-3781-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1817008 INFO (coreLoadExecutor-3781-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0
[junit4] 2> 1817083 INFO (coreLoadExecutor-3781-thread-1) [ ] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1817084 INFO (coreLoadExecutor-3781-thread-1) [ ] o.a.s.s.IndexSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\conf\schema.xml
[junit4] 2> 1817090 INFO (coreLoadExecutor-3781-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1817101 INFO (coreLoadExecutor-3781-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1817105 INFO (coreLoadExecutor-3781-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\conf'
[junit4] 2> 1817105 INFO (coreLoadExecutor-3781-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\
[junit4] 2> 1817105 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1817105 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\], dataDir=[null]
[junit4] 2> 1817107 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 1817109 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\data\
[junit4] 2> 1817109 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\data\index/
[junit4] 2> 1817110 WARN (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1817110 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\data\index
[junit4] 2> 1817110 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=96.6650390625, floorSegmentMB=1.732421875, forceMergeDeletesPctAllowed=11.479313264183242, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3057751205348932
[junit4] 2> 1817111 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@773cc5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e74c),segFN=segments_1,generation=1}
[junit4] 2> 1817111 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 1817113 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\data\
[junit4] 2> 1817116 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1817123 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1817124 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1817127 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1817128 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1817131 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 1817131 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/segments,/admin/plugins,standard,/replication,/admin/mbeans,/update/csv,/admin/logging,/config,/update/json/docs,/schema,/admin/ping,/admin/properties,lazy,/admin/system,/admin/luke,/admin/threads,/get,/admin/file,defaults,/update,/update/json
[junit4] 2> 1817133 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1817134 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
[junit4] 2> 1817134 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
[junit4] 2> 1817134 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=15, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6135612017968246]
[junit4] 2> 1817134 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@773cc5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36e74c),segFN=segments_1,generation=1}
[junit4] 2> 1817135 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 1817135 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@4a8121[collection1] main
[junit4] 2> 1817135 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\conf
[junit4] 2> 1817136 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\conf}
[junit4] 2> 1817136 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\conf
[junit4] 2> 1817136 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1817136 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1817136 INFO (searcherExecutor-3782-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4a8121[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1817136 INFO (coreLoadExecutor-3781-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1817495 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\collection1
[junit4] 2> 1817512 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529
[junit4] 2> 1817515 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@15b0348{/solr,null,AVAILABLE}
[junit4] 2> 1817516 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.e.j.s.ServerConnector Started ServerConnector@34a829{HTTP/1.1}{127.0.0.1:60034}
[junit4] 2> 1817516 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.e.j.s.Server Started @1822492ms
[junit4] 2> 1817517 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60034, solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\collection1\data}
[junit4] 2> 1817517 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@a5c9f1
[junit4] 2> 1817517 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\'
[junit4] 2> 1817571 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\solr.xml
[junit4] 2> 1817614 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\.
[junit4] 2> 1817614 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoreContainer New CoreContainer 16452689
[junit4] 2> 1817615 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\]
[junit4] 2> 1817615 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoreContainer loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\lib
[junit4] 2> 1817615 WARN (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\lib).
[junit4] 2> 1817631 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] 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> 1817637 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1817638 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1817638 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1817638 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1817640 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoresLocator Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\.
[junit4] 2> 1817643 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.SolrCore Created CoreDescriptor: {loadOnStartup=true, transient=false, schema=schema.xml, configSetProperties=configsetprops.json, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\.\collection1\, name=collection1, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\.\collection1, dataDir=data\, config=solrconfig.xml}
[junit4] 2> 1817644 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoresLocator Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\.\collection1\
[junit4] 2> 1817647 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoresLocator Found 1 core definitions
[junit4] 2> 1817648 INFO (coreLoadExecutor-3791-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\.\collection1\'
[junit4] 2> 1817649 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.s.SolrDispatchFilter user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1817649 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1817694 INFO (coreLoadExecutor-3791-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1817714 INFO (coreLoadExecutor-3791-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0
[junit4] 2> 1817749 INFO (coreLoadExecutor-3791-thread-1) [ ] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1817750 INFO (coreLoadExecutor-3791-thread-1) [ ] o.a.s.s.IndexSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\.\collection1\conf\schema.xml
[junit4] 2> 1817755 INFO (coreLoadExecutor-3791-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1817767 INFO (coreLoadExecutor-3791-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1817771 INFO (coreLoadExecutor-3791-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\.\collection1\conf'
[junit4] 2> 1817771 INFO (coreLoadExecutor-3791-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\.\collection1\
[junit4] 2> 1817771 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1817771 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\.\collection1\], dataDir=[null]
[junit4] 2> 1817772 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
[junit4] 2> 1817774 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\.\collection1\data\
[junit4] 2> 1817775 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\.\collection1\data\index/
[junit4] 2> 1817775 WARN (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\.\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1817775 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\.\collection1\data\index
[junit4] 2> 1817776 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=96.6650390625, floorSegmentMB=1.732421875, forceMergeDeletesPctAllowed=11.479313264183242, segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3057751205348932
[junit4] 2> 1817776 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@11b2d26 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13130ec),segFN=segments_1,generation=1}
[junit4] 2> 1817776 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 1817778 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\.\collection1\data\
[junit4] 2> 1817781 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1817788 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1817790 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1817792 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1817793 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1817795 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 1817796 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/segments,/admin/plugins,standard,/replication,/admin/mbeans,/update/csv,/admin/logging,/config,/update/json/docs,/schema,/admin/ping,/admin/properties,lazy,/admin/system,/admin/luke,/admin/threads,/get,/admin/file,defaults,/update,/update/json
[junit4] 2> 1817798 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1817798 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
[junit4] 2> 1817798 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
[junit4] 2> 1817799 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=15, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6135612017968246]
[junit4] 2> 1817799 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@11b2d26 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13130ec),segFN=segments_1,generation=1}
[junit4] 2> 1817799 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 1817799 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@18ee6b9[collection1] main
[junit4] 2> 1817800 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\.\collection1\conf
[junit4] 2> 1817800 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\.\collection1\conf}
[junit4] 2> 1817800 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using file:dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\.\collection1\conf
[junit4] 2> 1817800 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1817801 WARN (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1817801 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 1817801 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml
[junit4] 2> 1817801 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1817802 INFO (searcherExecutor-3792-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@18ee6b9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1817802 INFO (coreLoadExecutor-3791-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1818200 INFO (qtp26153610-8929) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 7
[junit4] 2> 1818205 INFO (qtp26153610-8928) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 1
[junit4] 2> 1818209 INFO (qtp26153610-8930) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 1
[junit4] 2> 1818213 INFO (qtp26153610-8931) [ x:collection1] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1818214 INFO (qtp26153610-8931) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@8f67d1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e8ed81),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@8f67d1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@e8ed81),segFN=segments_2,generation=2}
[junit4] 2> 1818214 INFO (qtp26153610-8931) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 2
[junit4] 2> 1818216 INFO (qtp26153610-8931) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@15bf1cd[collection1] main
[junit4] 2> 1818217 INFO (qtp26153610-8931) [ x:collection1] o.a.s.u.UpdateHandler end_commit_flush
[junit4] 2> 1818217 INFO (searcherExecutor-3761-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@15bf1cd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.3.0):C3)))}
[junit4] 2> 1818217 INFO (qtp26153610-8931) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=/solr path=/update params={wt=javabin&waitSearcher=true&softCommit=false&commit=true&version=2} {commit=} 0 4
[junit4] 2> 1818229 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1818232 INFO (qtp26153610-8932) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&qt=/replication&command=indexversion&version=2} status=0 QTime=0
[junit4] 2> 1818232 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 1818232 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1818232 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1818241 INFO (qtp26153610-8933) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&qt=/replication&generation=2&command=filelist&version=2} status=0 QTime=6
[junit4] 2> 1818241 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 10
[junit4] 2> 1818245 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\.\collection1\data\index.20150723140322393
[junit4] 2> 1818246 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Creating new IndexWriter...
[junit4] 2> 1818246 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 1818246 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
[junit4] 2> 1818246 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=23, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.13641178988579616]
[junit4] 2> 1818247 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@11b2d26 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13130ec),segFN=segments_1,generation=1}
[junit4] 2> 1818247 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 1818247 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1818247 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@11b2d26 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13130ec),segFN=segments_1,generation=1}
[junit4] 2> 1818247 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 1818247 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Closing IndexWriter...
[junit4] 2> 1818248 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 1818248 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Rollback old IndexWriter... core=collection1
[junit4] 2> 1818248 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting download to MockDirectoryWrapper(SimpleFSDirectory@C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\index-SimpleFSDirectory-005 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c83fda) fullCopy=false
[junit4] 2> 1818251 INFO (qtp26153610-8929) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&file=_0_Lucene50_0.tip&qt=/replication&generation=2&command=filecontent} status=0 QTime=0
[junit4] 2> 1818255 INFO (qtp26153610-8928) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&file=_0.si&qt=/replication&generation=2&command=filecontent} status=0 QTime=0
[junit4] 2> 1818261 INFO (qtp26153610-8930) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&file=_0.fdt&qt=/replication&generation=2&command=filecontent} status=0 QTime=0
[junit4] 2> 1818266 INFO (qtp26153610-8931) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&file=_0.nvd&qt=/replication&generation=2&command=filecontent} status=0 QTime=0
[junit4] 2> 1818298 INFO (qtp26153610-8932) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&file=_0_Lucene50_0.tim&qt=/replication&generation=2&command=filecontent} status=0 QTime=0
[junit4] 2> 1818304 INFO (qtp26153610-8933) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&file=_0_Lucene50_0.doc&qt=/replication&generation=2&command=filecontent} status=0 QTime=0
[junit4] 2> 1818308 INFO (qtp26153610-8929) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&file=_0.fdx&qt=/replication&generation=2&command=filecontent} status=0 QTime=0
[junit4] 2> 1818312 INFO (qtp26153610-8928) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&file=_0.nvm&qt=/replication&generation=2&command=filecontent} status=0 QTime=0
[junit4] 2> 1818316 INFO (qtp26153610-8930) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&file=_0.fnm&qt=/replication&generation=2&command=filecontent} status=0 QTime=0
[junit4] 2> 1818320 INFO (qtp26153610-8931) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=filestream&checksum=true&file=segments_2&qt=/replication&generation=2&command=filecontent} status=0 QTime=0
[junit4] 2> 1818321 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Total time taken for download : 0 secs
[junit4] 2> 1818329 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Creating new IndexWriter...
[junit4] 2> 1818329 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=15, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=71.7724609375, floorSegmentMB=0.7138671875, forceMergeDeletesPctAllowed=24.635915196908236, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1818330 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@11b2d26 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13130ec),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@11b2d26 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13130ec),segFN=segments_2,generation=2}
[junit4] 2> 1818330 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 2
[junit4] 2> 1818330 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1818331 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@19c0b02[collection1] main
[junit4] 2> 1818331 INFO (searcherExecutor-3792-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@19c0b02[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.3.0):C3)))}
[junit4] 2> 1818331 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\.\collection1\data\index.20150723140322393 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\.\collection1\data\index.20150723140322393;done=true>>]
[junit4] 2> 1818331 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-003\.\collection1\data\index.20150723140322393
[junit4] 2> 1818332 INFO (qtp31529610-8984) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wait=true&masterUrl=http://127.0.0.1:60025/solr/collection1/replication&command=fetchindex} status=0 QTime=97
[junit4] 2> 1818339 INFO (qtp31529610-8985) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={wt=javabin&q=*:*&sort=id+desc&version=2} hits=3 status=0 QTime=0
[junit4] 2> 1818339 INFO (TEST-TestReplicationHandler.doTestRepeater-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.SolrTestCaseJ4 Waited for 0ms and found 3 docs
[junit4] 2> 1818341 WARN (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 1818346 INFO (qtp31529610-8986) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&qt=/replication&command=indexversion&version=2} status=0 QTime=0
[junit4] 2> 1818346 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 2
[junit4] 2> 1818346 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1818346 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Starting replication process
[junit4] 2> 1818350 INFO (qtp31529610-8987) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=javabin&qt=/replication&generation=2&command=filelist&version=2} status=0 QTime=1
[junit4] 2> 1818351 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.h.IndexFetcher Number of files in latest index in master: 10
[junit4] 2> 1818353 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-002\.\collection1\data\index.20150723140322502
[junit4] 2> 1818353 INFO (explicit-fetchindex-cmd) [ x:collection1] o.a.s.u.DefaultSolrCoreState Creating
[...truncated too long message...]
p\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-027\.\collection1\data\index.20150723140515984
[junit4] 2> 1932059 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@d1fc7{/solr,null,UNAVAILABLE}
[junit4] 2> 1932062 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[56BD2D639E8ACD3E]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@1c37e2b{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 1932062 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[56BD2D639E8ACD3E]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=21461628
[junit4] 2> 1932066 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@c3ef0b
[junit4] 2> 1932066 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.a.s.u.UpdateHandler closing DirectUpdateHandler2{commits=2,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=3,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0}
[junit4] 2> 1932067 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 1932067 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1932067 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 1932067 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 1932068 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1932068 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-028\.\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-028\.\collection1\data\;done=false>>]
[junit4] 2> 1932068 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-028\.\collection1\data\
[junit4] 2> 1932068 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-028\.\collection1\data\index.20150723140515677 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-028\.\collection1\data\index.20150723140515677;done=false>>]
[junit4] 2> 1932068 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001\solr-instance-028\.\collection1\data\index.20150723140515677
[junit4] 2> 1932069 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[56BD2D639E8ACD3E]) [ x:collection1] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@b76ac8{/solr,null,UNAVAILABLE}
[junit4] 2> 1932070 INFO (SUITE-TestReplicationHandler-seed#[56BD2D639E8ACD3E]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_56BD2D639E8ACD3E-001
[junit4] 2> NOTE: test params are: codec=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=1, maxDocsPerChunk=4, blockSize=9), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=1, blockSize=9)), sim=DefaultSimilarity, locale=es, timezone=MST7MDT
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_80 (32-bit)/cpus=3,threads=1,free=171743040,total=441098240
[junit4] 2> NOTE: All tests run in this JVM: [TestDistribDocBasedVersion, DistributedIntervalFacetingTest, TestSurroundQueryParser, CursorMarkTest, TestTrackingShardHandlerFactory, TestPhraseSuggestions, TestHashQParserPlugin, TestSearcherReuse, TestLRUCache, SolrIndexSplitterTest, TestIndexingPerformance, ClusterStateTest, ZkControllerTest, TestManagedSchemaDynamicFieldResource, SpatialRPTFieldTypeTest, RequiredFieldsTest, TestCloudSchemaless, TestDistributedStatsComponentCardinality, TestCollectionAPI, UUIDFieldTest, BigEndianAscendingWordSerializerTest, TestSolrConfigHandler, SyncSliceTest, TestXIncludeConfig, LeaderElectionTest, SynonymTokenizerTest, DistribJoinFromCollectionTest, TestShortCircuitedRequests, TestOrdValues, ShowFileRequestHandlerTest, TestHashPartitioner, DistributedDebugComponentTest, TestPartialUpdateDeduplication, TestPerFieldSimilarity, TestExtendedDismaxParser, ClusterStateUpdateTest, TestSchemaManager, InfoHandlerTest, DocumentAnalysisRequestHandlerTest, HighlighterMaxOffsetTest, TestSolr4Spatial2, NoCacheHeaderTest, TestSolrIndexConfig, DirectUpdateHandlerTest, TestBlendedInfixSuggestions, LukeRequestHandlerTest, TestLFUCache, SimpleFacetsTest, SuggesterFSTTest, TestRandomRequestDistribution, TestInitQParser, HdfsLockFactoryTest, HdfsChaosMonkeySafeLeaderTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestHdfsUpdateLog, DirectSolrConnectionTest, TestLeaderElectionZkExpiry, TestCharFilters, TestCloudInspectUtil, JavabinLoaderTest, AlternateDirectoryTest, DeleteShardTest, ChangedSchemaMergeTest, TestDefaultSearchFieldResource, TestSort, TestBulkSchemaConcurrent, RankQueryTest, TestChildDocTransformer, ResponseLogComponentTest, TestSerializedLuceneMatchVersion, URLClassifyProcessorTest, HdfsDirectoryTest, TestJmxMonitoredMap, JSONWriterTest, SOLR749Test, IndexSchemaRuntimeFieldTest, TestUpdate, TestExactSharedStatsCache, OutOfBoxZkACLAndCredentialsProvidersTest, TestFieldTypeCollectionResource, NumericFieldsTest, TestTrieFacet, TestStandardQParsers, AliasIntegrationTest, BadComponentTest, DistribDocExpirationUpdateProcessorTest, HdfsBasicDistributedZkTest, TestSweetSpotSimilarityFactory, TestFunctionQuery, TestReqParamsAPI, TestSolrConfigHandlerConcurrent, CollectionsAPIAsyncDistributedZkTest, ChaosMonkeyNothingIsSafeTest, TestConfigSets, TestCloudManagedSchema, IgnoreCommitOptimizeUpdateProcessorFactoryTest, SuggesterTest, TestDistributedMissingSort, TestExpandComponent, TestClassNameShortening, TestSortingResponseWriter, TestStressLucene, TestComponentsName, ResponseHeaderTest, SliceStateTest, ParsingFieldUpdateProcessorsTest, TestQueryUtils, TestStressVersions, TestIndexSearcher, DistributedSuggestComponentTest, DeleteInactiveReplicaTest, TestNamedUpdateProcessors, SampleTest, SolrCloudExampleTest, PathHierarchyTokenizerFactoryTest, TestSolrDynamicMBean, DistributedExpandComponentTest, TestSchemaNameResource, TestFreeTextSuggestions, ZkCLITest, LeaderFailoverAfterPartitionTest, TestTolerantSearch, TestFieldCollectionResource, TestManagedSynonymFilterFactory, ZkStateWriterTest, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, ShardRoutingTest, TestReplicationHandler]
[junit4] Completed [272/518] on J0 in 116.68s, 13 tests, 1 failure <<< FAILURES!
[...truncated 785 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:720: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:667: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:59: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build.xml:230: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\common-build.xml:516: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:1447: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:1002: There were test failures: 518 suites, 2055 tests, 1 failure, 69 ignored (35 assumptions)
Total time: 76 minutes 25 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-5.x-Windows (64bit/jdk1.8.0_51) - Build #
4934 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Windows/4934/
Java: 64bit/jdk1.8.0_51 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.update.AutoCommitTest.testMaxTime
Error Message:
Exception during query
Stack Trace:
java.lang.RuntimeException: Exception during query
at __randomizedtesting.SeedInfo.seed([FD5F206DC99999D5:67AB5D8F570305E9]:0)
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:763)
at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:237)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
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:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1]
xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
</response>
request was:q=id:529&qt=standard&start=0&rows=20&version=2.2
at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:756)
... 40 more
Build Log:
[...truncated 10188 lines...]
[junit4] Suite: org.apache.solr.update.AutoCommitTest
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_FD5F206DC99999D5-001\init-core-data-001
[junit4] 2> 577972 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false)
[junit4] 2> 577973 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 577973 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\'
[junit4] 2> 577974 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 577974 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 578036 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 578043 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0
[junit4] 2> 578058 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 578060 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.s.IndexSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
[junit4] 2> 578067 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 578243 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 578254 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 578255 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 578265 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 578267 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 578271 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 578272 WARN (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 578272 WARN (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 578272 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 578272 WARN (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 578273 WARN (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 578273 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 578274 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr
[junit4] 2> 578274 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\'
[junit4] 2> 578295 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.c.CoreContainer New CoreContainer 1835363166
[junit4] 2> 578295 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\]
[junit4] 2> 578295 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.c.CoreContainer loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\lib
[junit4] 2> 578295 WARN (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\lib).
[junit4] 2> 578303 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-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> 578312 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 578315 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 578315 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 578315 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 578319 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=collection1, collection=collection1, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_FD5F206DC99999D5-001\init-core-data-001, shard=shard1}
[junit4] 2> 578320 INFO (coreLoadExecutor-2436-thread-1) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\'
[junit4] 2> 578330 INFO (coreLoadExecutor-2436-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 578330 INFO (coreLoadExecutor-2436-thread-1) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 578370 INFO (coreLoadExecutor-2436-thread-1) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 578384 INFO (coreLoadExecutor-2436-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0
[junit4] 2> 578414 INFO (coreLoadExecutor-2436-thread-1) [ ] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 578414 INFO (coreLoadExecutor-2436-thread-1) [ ] o.a.s.s.IndexSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
[junit4] 2> 578422 INFO (coreLoadExecutor-2436-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 578658 INFO (coreLoadExecutor-2436-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 578666 INFO (coreLoadExecutor-2436-thread-1) [ ] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 578669 INFO (coreLoadExecutor-2436-thread-1) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 578686 INFO (coreLoadExecutor-2436-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 578689 INFO (coreLoadExecutor-2436-thread-1) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 578692 INFO (coreLoadExecutor-2436-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 578692 WARN (coreLoadExecutor-2436-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 578693 WARN (coreLoadExecutor-2436-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 578693 INFO (coreLoadExecutor-2436-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 578693 WARN (coreLoadExecutor-2436-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 578693 WARN (coreLoadExecutor-2436-thread-1) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 578696 INFO (coreLoadExecutor-2436-thread-1) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf'
[junit4] 2> 578696 INFO (coreLoadExecutor-2436-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\
[junit4] 2> 578696 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 578697 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\], dataDir=[null]
[junit4] 2> 578697 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ca44c5d
[junit4] 2> 578698 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_FD5F206DC99999D5-001\init-core-data-001\
[junit4] 2> 578698 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_FD5F206DC99999D5-001\init-core-data-001\index/
[junit4] 2> 578698 WARN (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_FD5F206DC99999D5-001\init-core-data-001\index' doesn't exist. Creating new index...
[junit4] 2> 578698 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_FD5F206DC99999D5-001\init-core-data-001\index
[junit4] 2> 578699 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=938142460, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 578699 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1d011df0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@703a6715),segFN=segments_1,generation=1}
[junit4] 2> 578700 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 578703 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
[junit4] 2> 578703 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 578703 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 578704 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 578704 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 578704 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 578704 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 578704 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 578705 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 578705 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 578705 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 578705 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 578710 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 578711 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 578712 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 578714 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 578718 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 578719 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
[junit4] 2> 578720 WARN (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.c.SolrCore solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 578720 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 578721 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 578721 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 578723 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
[junit4] 2> 578723 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
[junit4] 2> 578723 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=5.7958984375, floorSegmentMB=1.6533203125, forceMergeDeletesPctAllowed=3.8418042859438515, segmentsPerTier=38.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.690879446347896
[junit4] 2> 578724 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1d011df0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@703a6715),segFN=segments_1,generation=1}
[junit4] 2> 578724 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 578724 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@811eaeb[collection1] main
[junit4] 2> 578724 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 578724 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 578724 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 578724 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 578733 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_FD5F206DC99999D5-001\init-core-data-001\
[junit4] 2> 578756 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 578918 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 578919 INFO (searcherExecutor-2437-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 578920 INFO (searcherExecutor-2437-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 578920 INFO (searcherExecutor-2437-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 578920 INFO (searcherExecutor-2437-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 578921 INFO (searcherExecutor-2437-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 578922 INFO (searcherExecutor-2437-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 578923 INFO (searcherExecutor-2437-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 578923 INFO (searcherExecutor-2437-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 578923 INFO (searcherExecutor-2437-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 578924 INFO (searcherExecutor-2437-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@811eaeb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 578925 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 578925 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 578925 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 578925 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1507540167471136768
[junit4] 2> 578929 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.u.UpdateLog Took 4 ms to seed version buckets with highest version 1507540167471136768
[junit4] 2> 578929 INFO (coreLoadExecutor-2436-thread-1) [ x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 578931 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 578938 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMaxTime
[junit4] 2> 578943 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1507540167485816832)} 0 4
[junit4] 2> 578946 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\'
[junit4] 2> 578949 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 578949 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.c.SolrResourceLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 579012 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 579025 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0
[junit4] 2> 579045 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 579046 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.s.IndexSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
[junit4] 2> 579053 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 579140 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 579151 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 579152 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 579157 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 579160 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 579164 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 579165 WARN (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 579166 WARN (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 579166 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 579166 WARN (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 579166 WARN (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 579171 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf'
[junit4] 2> 579171 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ ] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\
[junit4] 2> 579172 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\], dataDir=[C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_FD5F206DC99999D5-001\init-core-data-001\]
[junit4] 2> 579172 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ca44c5d
[junit4] 2> 579173 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_FD5F206DC99999D5-001\init-core-data-001\index/
[junit4] 2> 579178 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
[junit4] 2> 579178 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 579178 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 579178 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 579178 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 579178 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 579179 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 579179 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 579179 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 579179 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 579179 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 579179 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 579184 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 579187 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 579188 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 579189 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 579192 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_FD5F206DC99999D5-001\init-core-data-001\
[junit4] 2> 579196 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 579209 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
[junit4] 2> 579211 WARN (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 579212 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 579213 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
[junit4] 2> 579213 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
[junit4] 2> 579214 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@37d69877[collection1] main
[junit4] 2> 579214 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 579215 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 579215 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 579215 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 579217 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 579223 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 579224 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 579224 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 579224 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 579227 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.UpdateLog Took 3 ms to seed version buckets with highest version 1507540167485816832
[junit4] 2> 579227 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.DefaultSolrCoreState Creating new IndexWriter...
[junit4] 2> 579227 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.DefaultSolrCoreState Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 579227 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.DefaultSolrCoreState Closing old IndexWriter... core=collection1
[junit4] 2> 579231 INFO (searcherExecutor-2442-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 579232 INFO (searcherExecutor-2442-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 579232 INFO (searcherExecutor-2442-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 579232 INFO (searcherExecutor-2442-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 579233 INFO (searcherExecutor-2442-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 579234 INFO (searcherExecutor-2442-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 579234 INFO (searcherExecutor-2442-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 579235 INFO (searcherExecutor-2442-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 579235 INFO (searcherExecutor-2442-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 579236 INFO (searcherExecutor-2442-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@37d69877[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 579236 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=16, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6040717468115068]
[junit4] 2> 579237 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1d011df0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@703a6715),segFN=segments_1,generation=1}
[junit4] 2> 579238 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 579238 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 579238 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@1d011ccd[collection1] main
[junit4] 2> 579238 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.CoreContainer replacing core: collection1
[junit4] 2> 579239 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@6006decc
[junit4] 2> 579239 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.UpdateHandler closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=1,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
[junit4] 2> 579240 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 579253 INFO (searcherExecutor-2442-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1d011ccd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 579254 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[529 (1507540167812972544)]} 0 3
[junit4] 2> 579254 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 579256 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[530 (1507540167817166848)]} 0 5
[junit4] 2> 579256 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 579257 ERROR (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.SolrTestCaseJ4 REQUEST FAILED: xpath=//result[@numFound=1]
[junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] 2> <response>
[junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:q=id:529&qt=standard&start=0&rows=20&version=2.2
[junit4] 2> 579258 ERROR (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.SolrTestCaseJ4 REQUEST FAILED: q=id:529&qt=standard&start=0&rows=20&version=2.2:java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1]
[junit4] 2> xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] 2> <response>
[junit4] 2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
[junit4] 2> </response>
[junit4] 2>
[junit4] 2> request was:q=id:529&qt=standard&start=0&rows=20&version=2.2
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:756)
[junit4] 2> at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:237)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:497)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 579260 INFO (TEST-AutoCommitTest.testMaxTime-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testMaxTime
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=AutoCommitTest -Dtests.method=testMaxTime -Dtests.seed=FD5F206DC99999D5 -Dtests.slow=true -Dtests.locale=es_CR -Dtests.timezone=Iran -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.34s J1 | AutoCommitTest.testMaxTime <<<
[junit4] > Throwable #1: java.lang.RuntimeException: Exception during query
[junit4] > at __randomizedtesting.SeedInfo.seed([FD5F206DC99999D5:67AB5D8F570305E9]:0)
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:763)
[junit4] > at org.apache.solr.update.AutoCommitTest.testMaxTime(AutoCommitTest.java:237)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//result[@numFound=1]
[junit4] > xml response was: <?xml version="1.0" encoding="UTF-8"?>
[junit4] > <response>
[junit4] > <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><result name="response" numFound="0" start="0"></result>
[junit4] > </response>
[junit4] > request was:q=id:529&qt=standard&start=0&rows=20&version=2.2
[junit4] > at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:756)
[junit4] > ... 40 more
[junit4] 2> 579279 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testCommitWithin
[junit4] 2> 579292 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@510db900[collection1] realtime
[junit4] 2> 579293 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1507540167842332672)} 0 12
[junit4] 2> 579293 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\'
[junit4] 2> 579294 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrResourceLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 579294 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrResourceLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 579325 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 579336 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0
[junit4] 2> 579359 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 579360 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
[junit4] 2> 579365 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 579489 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 579502 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 579502 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 579509 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 579511 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 579513 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 579514 WARN (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 579514 WARN (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 579514 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 579514 WARN (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 579515 WARN (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 579515 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf'
[junit4] 2> 579516 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.CoreContainer Reloading SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\
[junit4] 2> 579516 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\], dataDir=[C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_FD5F206DC99999D5-001\init-core-data-001\]
[junit4] 2> 579516 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5ca44c5d
[junit4] 2> 579516 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_FD5F206DC99999D5-001\init-core-data-001\index/
[junit4] 2> 579518 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_FD5F206DC99999D5-001\init-core-data-001\
[junit4] 2> 579526 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
[junit4] 2> 579526 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 579526 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 579527 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 579527 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 579527 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 579527 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 579527 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 579527 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 579528 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 579528 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 579529 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 579531 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 579533 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 579535 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 579538 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 579552 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.PluginBag requestHandler : 'lazy' created with startup=lazy
[junit4] 2> 579559 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,lazy,/update/json/docs,/admin/luke,spellCheckWithWordbreak_Direct,tvrh,/get,/admin/properties,/update/json,mltrh,/admin/threads,/search-facet-def,/update/csv,/search-facet-invariants,/admin/segments,spellCheckCompRH1,/mlt,/admin/system,/replication,dismax,defaults,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,mock,/admin/ping,/update,/admin/file,/terms,spellCheckCompRH_Direct,/debug/dump
[junit4] 2> 579559 WARN (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 579560 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 579561 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
[junit4] 2> 579561 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
[junit4] 2> 579561 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@62cae87f[collection1] main
[junit4] 2> 579561 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO}
[junit4] 2> 579562 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
[junit4] 2> 579562 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 579562 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 579564 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 579575 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 579576 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 579576 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 579576 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 579578 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.UpdateLog Took 2 ms to seed version buckets with highest version 1507540167842332672
[junit4] 2> 579579 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.DefaultSolrCoreState Creating new IndexWriter...
[junit4] 2> 579579 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.DefaultSolrCoreState Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 579579 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.DefaultSolrCoreState Closing old IndexWriter... core=collection1
[junit4] 2> 579580 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1d011df0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@703a6715),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1d011df0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@703a6715),segFN=segments_2,generation=2}
[junit4] 2> 579580 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 2
[junit4] 2> 579581 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5682622687903762]
[junit4] 2> 579581 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 579581 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1d011df0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@703a6715),segFN=segments_2,generation=2}
[junit4] 2> 579582 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 2
[junit4] 2> 579582 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 579582 WARN (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore [collection1] PERFORMANCE WARNING: Overlapping onDeckSearchers=2
[junit4] 2> 579582 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 579582 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 579582 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 579582 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@1170973e[collection1] main
[junit4] 2> 579583 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 579583 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.CoreContainer replacing core: collection1
[junit4] 2> 579584 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@7f6f9bf
[junit4] 2> 579584 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.UpdateHandler closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommit maxTime=1000ms,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=2,adds=2,deletesById=0,deletesByQuery=1,errors=0,cumulative_adds=2,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
[junit4] 2> 579584 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 579591 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 579592 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 579593 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 579594 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@62cae87f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 579594 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 579595 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 579595 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 579595 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 579597 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 579598 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 579599 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 579599 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 579600 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 579602 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1170973e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 579606 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 579626 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[529 (1507540168203042816)]} 0 2
[junit4] 2> 579626 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 583938 INFO (commitScheduler-2449-thread-1) [ x:collection1] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 583947 INFO (commitScheduler-2449-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@2274c7f5[collection1] main
[junit4] 2> 583948 INFO (commitScheduler-2449-thread-1) [ x:collection1] o.a.s.u.UpdateHandler end_commit_flush
[junit4] 2> 583955 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2274c7f5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(5.3.0):c1)))}
[junit4] 2> 584213 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[530 (1507540173014958080)]} 0 4275
[junit4] 2> 584213 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 584214 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {delete=[529 (-1507540173017055232)]} 0 4276
[junit4] 2> 584214 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 585291 INFO (commitScheduler-2449-thread-1) [ x:collection1] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 585297 INFO (commitScheduler-2449-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@7dbc7eb0[collection1] main
[junit4] 2> 585297 INFO (commitScheduler-2449-thread-1) [ x:collection1] o.a.s.u.UpdateHandler end_commit_flush
[junit4] 2> 585299 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7dbc7eb0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(5.3.0):c1)))}
[junit4] 2> 585561 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:529&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 585562 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 585564 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[500 (1507540174431584256)]} 0 5539
[junit4] 2> 585564 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[500 (1507540174432632832)]} 0 5539
[junit4] 2> 585565 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[500 (1507540174433681408)]} 0 5539
[junit4] 2> 585565 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[500 (1507540174433681409)]} 0 5540
[junit4] 2> 585565 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[500 (1507540174433681410)]} 0 5540
[junit4] 2> 585565 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[500 (1507540174433681411)]} 0 5540
[junit4] 2> 585566 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[500 (1507540174434729984)]} 0 5540
[junit4] 2> 585566 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[500 (1507540174434729985)]} 0 5541
[junit4] 2> 585566 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[500 (1507540174434729986)]} 0 5541
[junit4] 2> 585566 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[500 (1507540174434729987)]} 0 5541
[junit4] 2> 585568 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:500&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 585568 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {delete=[530 (-1507540174436827136)]} 0 5542
[junit4] 2> 585569 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {delete=[530 (-1507540174436827137)]} 0 5543
[junit4] 2> 585569 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {delete=[530 (-1507540174437875712)]} 0 5543
[junit4] 2> 585569 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {delete=[530 (-1507540174437875713)]} 0 5543
[junit4] 2> 585570 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {delete=[530 (-1507540174438924288)]} 0 5543
[junit4] 2> 585570 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {delete=[530 (-1507540174438924289)]} 0 5543
[junit4] 2> 585570 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {delete=[530 (-1507540174438924290)]} 0 5544
[junit4] 2> 585570 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {delete=[530 (-1507540174438924291)]} 0 5544
[junit4] 2> 585570 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {delete=[530 (-1507540174438924292)]} 0 5544
[junit4] 2> 585571 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {delete=[530 (-1507540174439972864)]} 0 5544
[junit4] 2> 585571 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 586647 INFO (commitScheduler-2449-thread-1) [ x:collection1] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 586654 INFO (commitScheduler-2449-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@553e6c7f[collection1] main
[junit4] 2> 586654 INFO (commitScheduler-2449-thread-1) [ x:collection1] o.a.s.u.UpdateHandler end_commit_flush
[junit4] 2> 586656 INFO (searcherExecutor-2446-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@553e6c7f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(5.3.0):c10/9:delGen=1)))}
[junit4] 2> 586919 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:500&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 586921 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:530&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 586921 INFO (TEST-AutoCommitTest.testCommitWithin-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testCommitWithin
[junit4] 2> 586926 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Starting testMaxDocs
[junit4] 2> 586930 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@3ba8d84f[collection1] realtime
[junit4] 2> 586931 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {deleteByQuery=*:* (-1507540175861841920)} 0 5
[junit4] 2> 586932 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\'
[junit4] 2> 586933 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrResourceLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 586934 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrResourceLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 586953 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 586960 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0
[junit4] 2> 586985 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 586986 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
[junit4] 2> 586994 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 587083 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 587091 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 587093 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 587103 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 587107 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 587111 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 587112 WARN (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 587112 WARN (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 587113 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 587113 WARN (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 587113 WARN (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 587114 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf'
[junit4] 2> 587114 INFO (TEST-AutoCommitTest.testMaxDocs-se
[...truncated too long message...]
pdateProcessor [collection1] {add=[14 (1507540176142860288)]} 0 5
[junit4] 2> 587199 INFO (commitScheduler-2453-thread-1) [ x:collection1] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 587207 INFO (commitScheduler-2453-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@2ed3d5e6[collection1] main
[junit4] 2> 587207 INFO (commitScheduler-2453-thread-1) [ x:collection1] o.a.s.u.UpdateHandler end_commit_flush
[junit4] 2> 587207 INFO (searcherExecutor-2450-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2ed3d5e6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(5.3.0):c15)))}
[junit4] 2> 587451 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.u.p.LogUpdateProcessor [collection1] {add=[15 (1507540176410247168)]} 0 265
[junit4] 2> 587452 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:14&qt=standard&start=0&rows=20&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 587453 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=id:15&qt=standard&start=0&rows=20&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 587453 INFO (TEST-AutoCommitTest.testMaxDocs-seed#[FD5F206DC99999D5]) [ x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testMaxDocs
[junit4] 2> 587454 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ x:collection1] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 587454 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ x:collection1] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1835363166
[junit4] 2> 587454 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@377fd668
[junit4] 2> 587454 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ x:collection1] o.a.s.u.UpdateHandler closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommit maxDocs=14,soft autocommits=1,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=16,adds=1,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=16,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0}
[junit4] 2> 587454 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 587454 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 587455 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
[junit4] 2> 587455 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ x:collection1] o.a.s.u.UpdateHandler Committing on IndexWriter close.
[junit4] 2> 587460 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1d011df0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@703a6715),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1d011df0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@703a6715),segFN=segments_4,generation=4}
[junit4] 2> 587460 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ x:collection1] o.a.s.c.SolrCore newest commit generation = 4
[junit4] 2> 587461 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 587478 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 587478 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_FD5F206DC99999D5-001\init-core-data-001\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_FD5F206DC99999D5-001\init-core-data-001\index;done=false>>]
[junit4] 2> 587478 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_FD5F206DC99999D5-001\init-core-data-001\index
[junit4] 2> 587478 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_FD5F206DC99999D5-001\init-core-data-001\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_FD5F206DC99999D5-001\init-core-data-001\;done=false>>]
[junit4] 2> 587479 INFO (SUITE-AutoCommitTest-seed#[FD5F206DC99999D5]-worker) [ x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_FD5F206DC99999D5-001\init-core-data-001\
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J1\temp\solr.update.AutoCommitTest_FD5F206DC99999D5-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene53): {range_facet_l_dv=BlockTreeOrds(blocksize=128), _version_=Lucene50(blocksize=128), field_t=BlockTreeOrds(blocksize=128), multiDefault=FST50, subject=FST50, intDefault=Lucene50(blocksize=128), id=BlockTreeOrds(blocksize=128), range_facet_i_dv=FST50, text=PostingsFormat(name=LuceneFixedGap), range_facet_l=FST50, timestamp=FST50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Lucene50), timestamp=DocValuesFormat(name=Lucene50)}, sim=DefaultSimilarity, locale=es_CR, timezone=Iran
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.8.0_51 (64-bit)/cpus=3,threads=1,free=376165288,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [CollectionsAPIAsyncDistributedZkTest, TestLuceneMatchVersion, UpdateRequestProcessorFactoryTest, TestSolrConfigHandlerConcurrent, ParsingFieldUpdateProcessorsTest, TermVectorComponentTest, SolrIndexConfigTest, AnalyticsQueryTest, DocumentBuilderTest, RAMDirectoryFactoryTest, TestQuerySenderNoQuery, SimplePostToolTest, SpellPossibilityIteratorTest, CSVRequestHandlerTest, TestBulkSchemaConcurrent, ExternalFileFieldSortTest, TestSolr4Spatial2, TestRecovery, RequestLoggingTest, TestConfigReload, TestInitParams, FullHLLTest, OverseerStatusTest, CollectionReloadTest, OverseerCollectionProcessorTest, TestBulkSchemaAPI, TestSolrConfigHandlerCloud, TestEmbeddedSolrServerConstructors, SchemaVersionSpecificBehaviorTest, TestFiltering, TestInitQParser, DirectSolrSpellCheckerTest, TestSolrCoreProperties, RegexBoostProcessorTest, DeleteInactiveReplicaTest, DisMaxRequestHandlerTest, TestSolr4Spatial, BasicFunctionalityTest, DataDrivenBlockJoinTest, TestInfoStreamLogging, EchoParamsTest, LeaderInitiatedRecoveryOnCommitTest, RecoveryAfterSoftCommitTest, LeaderElectionIntegrationTest, TestCollapseQParserPlugin, SolrCmdDistributorTest, TestTrie, DistributedFacetPivotLongTailTest, ResponseHeaderTest, ReplicationFactorTest, CopyFieldTest, StatelessScriptUpdateProcessorFactoryTest, HighlighterTest, TestManagedResource, OpenExchangeRatesOrgProviderTest, UpdateParamsTest, TestSolrJ, ChangedSchemaMergeTest, DistributedFacetPivotSmallAdvancedTest, TestSolrQueryParser, TestBlobHandler, HdfsWriteToMultipleCollectionsTest, SpellingQueryConverterTest, TestCustomDocTransformer, AnalyticsMergeStrategyTest, DistributedTermsComponentTest, AutoCommitTest]
[junit4] Completed [112/518] on J1 in 9.55s, 3 tests, 1 error <<< FAILURES!
[...truncated 1284 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:721: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:667: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:59: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build.xml:230: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\common-build.xml:516: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:1447: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:1002: There were test failures: 518 suites, 2055 tests, 1 error, 69 ignored (35 assumptions)
Total time: 65 minutes 4 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any