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 2016/10/07 06:13:44 UTC
[JENKINS] Lucene-Solr-6.x-Windows (32bit/jdk1.8.0_102) - Build #
500 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/500/
Java: 32bit/jdk1.8.0_102 -server -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication
Error Message:
[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-021\.\collection1\data\index.20161007092735481, C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-021\.\collection1\data\snapshot_metadata, C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-021\.\collection1\data\, C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-021\.\collection1\data\index.20161007092735625] expected:<3> but was:<4>
Stack Trace:
java.lang.AssertionError: [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-021\.\collection1\data\index.20161007092735481, C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-021\.\collection1\data\snapshot_metadata, C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-021\.\collection1\data\, C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-021\.\collection1\data\index.20161007092735625] expected:<3> but was:<4>
at __randomizedtesting.SeedInfo.seed([5E750593413ADA7B:A906EBCB87D2759D]: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:904)
at org.apache.solr.handler.TestReplicationHandler.doTestIndexAndConfigAliasReplication(TestReplicationHandler.java:1336)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11695 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\init-core-data-001
[junit4] 2> 1491963 INFO (SUITE-TestReplicationHandler-seed#[5E750593413ADA7B]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
[junit4] 2> 1491964 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestIndexFetchWithMasterUrl
[junit4] 2> 1491966 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-001\collection1
[junit4] 2> 1491979 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1491980 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@17bf425{/solr,null,AVAILABLE}
[junit4] 2> 1491984 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1ca0e5a{HTTP/1.1,[http/1.1]}{127.0.0.1:50742}
[junit4] 2> 1491984 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.e.j.s.Server Started @1496838ms
[junit4] 2> 1491984 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-001\collection1\data, hostContext=/solr, hostPort=50742}
[junit4] 2> 1491984 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.3.0
[junit4] 2> 1491987 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1491987 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1491987 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-07T05:26:25.342Z
[junit4] 2> 1491988 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-001\solr.xml
[junit4] 2> 1492005 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1492014 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-001\.
[junit4] 2> 1492014 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1492036 INFO (coreLoadExecutor-5424-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1492044 INFO (coreLoadExecutor-5424-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1492049 INFO (coreLoadExecutor-5424-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1492056 INFO (coreLoadExecutor-5424-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-001\.\collection1
[junit4] 2> 1492056 INFO (coreLoadExecutor-5424-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-001\.\collection1\data\]
[junit4] 2> 1492057 INFO (coreLoadExecutor-5424-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7222373725001215]
[junit4] 2> 1492078 INFO (coreLoadExecutor-5424-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1492078 INFO (coreLoadExecutor-5424-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1492078 INFO (coreLoadExecutor-5424-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=11, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.828743368805664]
[junit4] 2> 1492078 INFO (coreLoadExecutor-5424-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1516885[collection1] main]
[junit4] 2> 1492079 INFO (coreLoadExecutor-5424-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-001\collection1\conf
[junit4] 2> 1492079 INFO (coreLoadExecutor-5424-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1492079 INFO (coreLoadExecutor-5424-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1492080 INFO (searcherExecutor-5425-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1516885[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1492517 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-002\collection1
[junit4] 2> 1492527 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1492528 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@111e4de{/solr,null,AVAILABLE}
[junit4] 2> 1492531 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1be54ac{HTTP/1.1,[http/1.1]}{127.0.0.1:50791}
[junit4] 2> 1492531 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.e.j.s.Server Started @1497384ms
[junit4] 2> 1492531 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-002\collection1\data, hostContext=/solr, hostPort=50791}
[junit4] 2> 1492532 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.3.0
[junit4] 2> 1492532 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1492532 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1492532 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-07T05:26:25.887Z
[junit4] 2> 1492533 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-002\solr.xml
[junit4] 2> 1492538 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1492545 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-002\.
[junit4] 2> 1492545 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1492568 INFO (coreLoadExecutor-5434-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1492576 INFO (coreLoadExecutor-5434-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1492579 INFO (coreLoadExecutor-5434-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1492582 INFO (coreLoadExecutor-5434-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-002\.\collection1
[junit4] 2> 1492582 INFO (coreLoadExecutor-5434-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-002\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-002\.\collection1\data\]
[junit4] 2> 1492583 INFO (coreLoadExecutor-5434-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7222373725001215]
[junit4] 2> 1492593 INFO (coreLoadExecutor-5434-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1492594 INFO (coreLoadExecutor-5434-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1492594 INFO (coreLoadExecutor-5434-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=11, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.828743368805664]
[junit4] 2> 1492594 INFO (coreLoadExecutor-5434-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@e2bf20[collection1] main]
[junit4] 2> 1492594 INFO (coreLoadExecutor-5434-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-002\collection1\conf
[junit4] 2> 1492597 INFO (coreLoadExecutor-5434-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 1492597 INFO (searcherExecutor-5435-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@e2bf20[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1492710 INFO (qtp6535699-12703) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 1492710 INFO (indexFetcher-5439-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's generation: 1
[junit4] 2> 1492710 INFO (indexFetcher-5439-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Master's version: 0
[junit4] 2> 1492710 INFO (indexFetcher-5439-thread-1) [ x:collection1] o.a.s.h.IndexFetcher Slave's generation: 1
[junit4] 2> 1493050 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@1be54ac{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1493050 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=26850480
[junit4] 2> 1493051 INFO (coreCloseExecutor-5440-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@751d
[junit4] 2> 1493053 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@111e4de{/solr,null,UNAVAILABLE}
[junit4] 2> 1493057 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 1493058 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@b302f0{/solr,null,AVAILABLE}
[junit4] 2> 1493062 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.e.j.s.ServerConnector Started ServerConnector@132e1c8{HTTP/1.1,[http/1.1]}{127.0.0.1:50812}
[junit4] 2> 1493062 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.e.j.s.Server Started @1497915ms
[junit4] 2> 1493062 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-002\collection1\data, hostContext=/solr, hostPort=50812}
[junit4] 2> 1493062 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.3.0
[junit4] 2> 1493062 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1493062 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1493062 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-07T05:26:26.417Z
[junit4] 2> 1493062 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-002\solr.xml
[junit4] 2> 1493069 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1493073 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-002\.
[junit4] 2> 1493073 INFO (TEST-TestReplicationHandler.doTestIndexFetchWithMasterUrl-seed#[5E750593413ADA7B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1493083 INFO (coreLoadExecutor-5447-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 1493092 INFO (coreLoadExecutor-5447-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1493094 INFO (coreLoadExecutor-5447-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1493095 INFO (coreLoadExecutor-5447-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-002\.\collection1
[junit4] 2> 1493095 INFO (coreLoadExecutor-5447-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-002\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-002\.\collection1\data\]
[junit4] 2> 1493096 INFO (coreLoadExecutor-5447-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7222373725001215]
[junit4] 2> 1493130 INFO (coreLoadExecutor-5447-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1493130 INFO (coreLoadExecutor-5447-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1493130 INFO (coreLoadExecutor-5447-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=11, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.828743368805664]
[junit4] 2> 1493130 INFO (coreLoadExecutor-5447-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@11d702f[collection1] main]
[junit4] 2> 1493130 INFO (coreLoadExecutor-5447-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001\solr-instance-002\collection1\conf
[junit4] 2> 1493131 INFO (coreLoadExecutor-5447-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1493131 INFO (searcherExecutor-5448-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@11d702f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1493579 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1493579 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 1493582 INFO (qtp10018463-12743) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1493582 INFO (qtp10018463-12743) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 1493584 INFO (qtp10018463-12746) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1493584 INFO (qtp10018463-12746) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@59c41b
[junit4] 2> 1493586 INFO (qtp10018463-12746) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a3321b[collection1] main]
[junit4] 2> 1493586 INFO (qtp10018463-12746) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1493586 INFO (searcherExecutor-5448-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1a3321b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1493587 INFO (qtp10018463-12746) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 1493591 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0]} 0 1
[junit4] 2> 1493592 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1]} 0 0
[junit4] 2> 1493593 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2]} 0 0
[junit4] 2> 1493595 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 1493598 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 1493600 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5]} 0 0
[junit4] 2> 1493603 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6]} 0 0
[junit4] 2> 1493605 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7]} 0 0
[junit4] 2> 1493608 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8]} 0 0
[junit4] 2> 1493610 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9]} 0 0
[junit4] 2> 1493612 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10]} 0 0
[junit4] 2> 1493615 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11]} 0 0
[junit4] 2> 1493617 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12]} 0 0
[junit4] 2> 1493620 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[13]} 0 0
[junit4] 2> 1493623 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[14]} 0 0
[junit4] 2> 1493625 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[15]} 0 0
[junit4] 2> 1493628 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16]} 0 0
[junit4] 2> 1493630 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17]} 0 0
[junit4] 2> 1493633 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[18]} 0 0
[junit4] 2> 1493635 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[19]} 0 0
[junit4] 2> 1493636 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20]} 0 0
[junit4] 2> 1493639 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[21]} 0 0
[junit4] 2> 1493641 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[22]} 0 0
[junit4] 2> 1493644 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[23]} 0 0
[junit4] 2> 1493645 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[24]} 0 0
[junit4] 2> 1493647 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[25]} 0 0
[junit4] 2> 1493649 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[26]} 0 0
[junit4] 2> 1493651 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[27]} 0 0
[junit4] 2> 1493652 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[28]} 0 0
[junit4] 2> 1493655 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[29]} 0 0
[junit4] 2> 1493656 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[30]} 0 0
[junit4] 2> 1493657 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[31]} 0 0
[junit4] 2> 1493659 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[32]} 0 0
[junit4] 2> 1493660 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[33]} 0 0
[junit4] 2> 1493661 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[34]} 0 0
[junit4] 2> 1493664 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[35]} 0 0
[junit4] 2> 1493666 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[36]} 0 0
[junit4] 2> 1493667 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[37]} 0 0
[junit4] 2> 1493670 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[38]} 0 0
[junit4] 2> 1493671 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[39]} 0 0
[junit4] 2> 1493672 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[40]} 0 0
[junit4] 2> 1493673 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[41]} 0 0
[junit4] 2> 1493675 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[42]} 0 0
[junit4] 2> 1493676 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[43]} 0 0
[junit4] 2> 1493677 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[44]} 0 0
[junit4] 2> 1493678 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[45]} 0 0
[junit4] 2> 1493679 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[46]} 0 0
[junit4] 2> 1493682 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[47]} 0 0
[junit4] 2> 1493683 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[48]} 0 0
[junit4] 2> 1493684 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[49]} 0 0
[junit4] 2> 1493685 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[50]} 0 0
[junit4] 2> 1493686 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[51]} 0 0
[junit4] 2> 1493687 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[52]} 0 0
[junit4] 2> 1493688 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[53]} 0 0
[junit4] 2> 1493690 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[54]} 0 0
[junit4] 2> 1493691 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[55]} 0 0
[junit4] 2> 1493692 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[56]} 0 0
[junit4] 2> 1493694 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[57]} 0 0
[junit4] 2> 1493695 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[58]} 0 0
[junit4] 2> 1493696 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[59]} 0 0
[junit4] 2> 1493697 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[60]} 0 0
[junit4] 2> 1493700 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[61]} 0 0
[junit4] 2> 1493701 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[62]} 0 0
[junit4] 2> 1493702 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[63]} 0 0
[junit4] 2> 1493703 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[64]} 0 0
[junit4] 2> 1493705 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[65]} 0 0
[junit4] 2> 1493707 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[66]} 0 0
[junit4] 2> 1493709 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[67]} 0 0
[junit4] 2> 1493710 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[68]} 0 0
[junit4] 2> 1493711 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[69]} 0 0
[junit4] 2> 1493712 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[70]} 0 0
[junit4] 2> 1493715 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[71]} 0 0
[junit4] 2> 1493716 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[72]} 0 0
[junit4] 2> 1493717 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[73]} 0 0
[junit4] 2> 1493720 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[74]} 0 0
[junit4] 2> 1493723 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[75]} 0 0
[junit4] 2> 1493724 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[76]} 0 0
[junit4] 2> 1493725 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[77]} 0 0
[junit4] 2> 1493728 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[78]} 0 0
[junit4] 2> 1493729 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[79]} 0 0
[junit4] 2> 1493731 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[80]} 0 0
[junit4] 2> 1493732 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[81]} 0 0
[junit4] 2> 1493733 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[82]} 0 0
[junit4] 2> 1493735 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[83]} 0 0
[junit4] 2> 1493736 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[84]} 0 0
[junit4] 2> 1493737 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[85]} 0 0
[junit4] 2> 1493739 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[86]} 0 0
[junit4] 2> 1493742 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[87]} 0 0
[junit4] 2> 1493744 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[88]} 0 0
[junit4] 2> 1493745 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[89]} 0 0
[junit4] 2> 1493746 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[90]} 0 0
[junit4] 2> 1493748 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[91]} 0 0
[junit4] 2> 1493749 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[92]} 0 0
[junit4] 2> 1493751 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[93]} 0 0
[junit4] 2> 1493753 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[94]} 0 0
[junit4] 2> 1493754 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[95]} 0 0
[junit4] 2> 1493757 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[96]} 0 0
[junit4] 2> 1493758 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[97]} 0 0
[junit4] 2> 1493759 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[98]} 0 0
[junit4] 2> 1493760 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[99]} 0 0
[junit4] 2> 1493761 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[100]} 0 0
[junit4] 2> 1493762 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[101]} 0 0
[junit4] 2> 1493763 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[102]} 0 0
[junit4] 2> 1493764 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[103]} 0 0
[junit4] 2> 1493765 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[104]} 0 0
[junit4] 2> 1493767 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[105]} 0 0
[junit4] 2> 1493769 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[106]} 0 0
[junit4] 2> 1493770 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[107]} 0 0
[junit4] 2> 1493772 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[108]} 0 0
[junit4] 2> 1493773 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[109]} 0 0
[junit4] 2> 1493775 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[110]} 0 0
[junit4] 2> 1493776 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[111]} 0 0
[junit4] 2> 1493778 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[112]} 0 0
[junit4] 2> 1493780 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[113]} 0 0
[junit4] 2> 1493782 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[114]} 0 0
[junit4] 2> 1493784 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[115]} 0 0
[junit4] 2> 1493786 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[116]} 0 0
[junit4] 2> 1493788 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[117]} 0 0
[junit4] 2> 1493789 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[118]} 0 0
[junit4] 2> 1493790 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[119]} 0 0
[junit4] 2> 1493791 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[120]} 0 0
[junit4] 2> 1493792 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[121]} 0 0
[junit4] 2> 1493794 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[122]} 0 0
[junit4] 2> 1493795 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[123]} 0 0
[junit4] 2> 1493796 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[124]} 0 0
[junit4] 2> 1493799 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[125]} 0 0
[junit4] 2> 1493800 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[126]} 0 0
[junit4] 2> 1493802 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[127]} 0 1
[junit4] 2> 1493803 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[128]} 0 0
[junit4] 2> 1493805 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[129]} 0 0
[junit4] 2> 1493807 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[130]} 0 0
[junit4] 2> 1493809 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[131]} 0 0
[junit4] 2> 1493810 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[132]} 0 0
[junit4] 2> 1493812 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[133]} 0 0
[junit4] 2> 1493813 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[134]} 0 0
[junit4] 2> 1493815 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[135]} 0 0
[junit4] 2> 1493816 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[136]} 0 0
[junit4] 2> 1493818 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[137]} 0 0
[junit4] 2> 1493820 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[138]} 0 0
[junit4] 2> 1493823 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[139]} 0 0
[junit4] 2> 1493825 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[140]} 0 0
[junit4] 2> 1493826 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[141]} 0 0
[junit4] 2> 1493828 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[142]} 0 0
[junit4] 2> 1493829 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[143]} 0 0
[junit4] 2> 1493831 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[144]} 0 0
[junit4] 2> 1493833 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[145]} 0 0
[junit4] 2> 1493834 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[146]} 0 0
[junit4] 2> 1493836 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[147]} 0 0
[junit4] 2> 1493837 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[148]} 0 0
[junit4] 2> 1493838 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[149]} 0 0
[junit4] 2> 1493840 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[150]} 0 0
[junit4] 2> 1493841 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[151]} 0 0
[junit4] 2> 1493843 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[152]} 0 0
[junit4] 2> 1493844 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[153]} 0 0
[junit4] 2> 1493845 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[154]} 0 0
[junit4] 2> 1493848 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[155]} 0 0
[junit4] 2> 1493850 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[156]} 0 0
[junit4] 2> 1493852 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[157]} 0 0
[junit4] 2> 1493855 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[158]} 0 0
[junit4] 2> 1493856 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[159]} 0 0
[junit4] 2> 1493857 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[160]} 0 0
[junit4] 2> 1493858 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[161]} 0 0
[junit4] 2> 1493860 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[162]} 0 0
[junit4] 2> 1493861 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[163]} 0 0
[junit4] 2> 1493862 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[164]} 0 0
[junit4] 2> 1493864 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[165]} 0 0
[junit4] 2> 1493865 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[166]} 0 0
[junit4] 2> 1493867 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[167]} 0 0
[junit4] 2> 1493869 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[168]} 0 0
[junit4] 2> 1493870 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[169]} 0 0
[junit4] 2> 1493871 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[170]} 0 0
[junit4] 2> 1493873 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[171]} 0 0
[junit4] 2> 1493874 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[172]} 0 0
[junit4] 2> 1493875 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[173]} 0 0
[junit4] 2> 1493876 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[174]} 0 0
[junit4] 2> 1493878 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[175]} 0 0
[junit4] 2> 1493880 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[176]} 0 0
[junit4] 2> 1493881 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[177]} 0 0
[junit4] 2> 1493883 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[178]} 0 0
[junit4] 2> 1493884 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[179]} 0 0
[junit4] 2> 1493886 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[180]} 0 0
[junit4] 2> 1493887 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[181]} 0 0
[junit4] 2> 1493889 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[182]} 0 0
[junit4] 2> 1493890 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[183]} 0 0
[junit4] 2> 1493891 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[184]} 0 0
[junit4] 2> 1493892 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[185]} 0 0
[junit4] 2> 1493894 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[186]} 0 0
[junit4] 2> 1493895 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[187]} 0 0
[junit4] 2> 1493896 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[188]} 0 0
[junit4] 2> 1493897 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[189]} 0 0
[junit4] 2> 1493898 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[190]} 0 0
[junit4] 2> 1493900 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[191]} 0 0
[junit4] 2> 1493901 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[192]} 0 0
[junit4] 2> 1493903 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[193]} 0 0
[junit4] 2> 1493904 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[194]} 0 0
[junit4] 2> 1493906 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[195]} 0 0
[junit4] 2> 1493908 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[196]} 0 0
[junit4] 2> 1493909 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[197]} 0 0
[junit4] 2> 1493910 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[198]} 0 0
[junit4] 2> 1493911 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[199]} 0 0
[junit4] 2> 1493912 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[200]} 0 0
[junit4] 2> 1493913 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[201]} 0 0
[junit4] 2> 1493914 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[202]} 0 0
[junit4] 2> 1493915 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[203]} 0 0
[junit4] 2> 1493916 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[204]} 0 0
[junit4] 2> 1493917 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[205]} 0 0
[junit4] 2> 1493918 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[206]} 0 0
[junit4] 2> 1493920 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[207]} 0 0
[junit4] 2> 1493921 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[208]} 0 0
[junit4] 2> 1493923 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[209]} 0 0
[junit4] 2> 1493924 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[210]} 0 0
[junit4] 2> 1493925 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[211]} 0 0
[junit4] 2> 1493927 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[212]} 0 0
[junit4] 2> 1493929 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[213]} 0 0
[junit4] 2> 1493930 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[214]} 0 0
[junit4] 2> 1493931 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[215]} 0 0
[junit4] 2> 1493932 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[216]} 0 0
[junit4] 2> 1493933 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[217]} 0 0
[junit4] 2> 1493935 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[218]} 0 0
[junit4] 2> 1493937 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[219]} 0 0
[junit4] 2> 1493939 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[220]} 0 0
[junit4] 2> 1493940 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[221]} 0 0
[junit4] 2> 1493941 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[222]} 0 0
[junit4] 2> 1493942 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[223]} 0 0
[junit4] 2> 1493943 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[224]} 0 0
[junit4] 2> 1493945 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[225]} 0 0
[junit4] 2> 1493946 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[226]} 0 0
[junit4] 2> 1493947 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[227]} 0 0
[junit4] 2> 1493948 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[228]} 0 0
[junit4] 2> 1493949 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[229]} 0 0
[junit4] 2> 1493951 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[230]} 0 0
[junit4] 2> 1493952 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[231]} 0 0
[junit4] 2> 1493954 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[232]} 0 0
[junit4] 2> 1493955 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[233]} 0 0
[junit4] 2> 1493956 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[234]} 0 0
[junit4] 2> 1493957 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[235]} 0 0
[junit4] 2> 1493958 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[236]} 0 0
[junit4] 2> 1493959 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[237]} 0 0
[junit4] 2> 1493961 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[238]} 0 0
[junit4] 2> 1493962 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[239]} 0 0
[junit4] 2> 1493963 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[240]} 0 0
[junit4] 2> 1493965 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[241]} 0 0
[junit4] 2> 1493966 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[242]} 0 0
[junit4] 2> 1493968 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[243]} 0 0
[junit4] 2> 1493970 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[244]} 0 0
[junit4] 2> 1493971 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[245]} 0 0
[junit4] 2> 1493972 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[246]} 0 0
[junit4] 2> 1493973 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[247]} 0 0
[junit4] 2> 1493974 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[248]} 0 0
[junit4] 2> 1493975 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[249]} 0 0
[junit4] 2> 1493976 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[250]} 0 0
[junit4] 2> 1493977 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[251]} 0 0
[junit4] 2> 1493979 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[252]} 0 0
[junit4] 2> 1493981 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[253]} 0 0
[junit4] 2> 1493982 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[254]} 0 0
[junit4] 2> 1493983 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[255]} 0 0
[junit4] 2> 1493984 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[256]} 0 0
[junit4] 2> 1493985 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[257]} 0 0
[junit4] 2> 1493986 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[258]} 0 0
[junit4] 2> 1493987 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[259]} 0 0
[junit4] 2> 1493988 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[260]} 0 0
[junit4] 2> 1493989 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[261]} 0 0
[junit4] 2> 1493991 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[262]} 0 0
[junit4] 2> 1493992 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[263]} 0 0
[junit4] 2> 1493993 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[264]} 0 0
[junit4] 2> 1493995 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[265]} 0 0
[junit4] 2> 1493996 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[266]} 0 0
[junit4] 2> 1493997 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[267]} 0 0
[junit4] 2> 1493999 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[268]} 0 0
[junit4] 2> 1494000 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[269]} 0 0
[junit4] 2> 1494001 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[270]} 0 0
[junit4] 2> 1494003 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[271]} 0 0
[junit4] 2> 1494005 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[272]} 0 0
[junit4] 2> 1494006 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[273]} 0 0
[junit4] 2> 1494007 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[274]} 0 0
[junit4] 2> 1494008 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[275]} 0 0
[junit4] 2> 1494009 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[276]} 0 0
[junit4] 2> 1494011 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[277]} 0 0
[junit4] 2> 1494012 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[278]} 0 0
[junit4] 2> 1494013 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[279]} 0 0
[junit4] 2> 1494015 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[280]} 0 0
[junit4] 2> 1494016 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[281]} 0 0
[junit4] 2> 1494017 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[282]} 0 0
[junit4] 2> 1494019 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[283]} 0 0
[junit4] 2> 1494020 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[284]} 0 0
[junit4] 2> 1494022 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[285]} 0 0
[junit4] 2> 1494023 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[286]} 0 0
[junit4] 2> 1494024 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[287]} 0 0
[junit4] 2> 1494026 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[288]} 0 0
[junit4] 2> 1494027 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[289]} 0 0
[junit4] 2> 1494028 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[290]} 0 0
[junit4] 2> 1494030 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[291]} 0 0
[junit4] 2> 1494031 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[292]} 0 0
[junit4] 2> 1494032 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[293]} 0 0
[junit4] 2> 1494033 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[294]} 0 0
[junit4] 2> 1494035 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[295]} 0 0
[junit4] 2> 1494037 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[296]} 0 0
[junit4] 2> 1494039 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[297]} 0 0
[junit4] 2> 1494040 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[298]} 0 0
[junit4] 2> 1494041 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[299]} 0 0
[junit4] 2> 1494043 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[300]} 0 0
[junit4] 2> 1494044 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[301]} 0 0
[junit4] 2> 1494045 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[302]} 0 0
[junit4] 2> 1494047 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[303]} 0 0
[junit4] 2> 1494048 INFO (qtp6535699-12708) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[304]} 0 0
[junit4] 2> 1494049 INFO (qtp6535699-12707) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[305]} 0 0
[junit4] 2> 1494051 INFO (qtp6535699-12705) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[306]} 0 0
[junit4] 2> 1494052 INFO (qtp6535699-12703) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[307]} 0 0
[junit4] 2> 1494054 INFO (qtp6535699-12706) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[308]} 0 0
[junit4] 2> 1494056 INFO (qtp6535699-12709) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[309]} 0 0
[junit4] 2> 1494057 INFO (qtp6535699-12710) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [co
[...truncated too long message...]
0\collection1\conf
[junit4] 2> 1578958 INFO (Thread-2790) [ x:collection1] o.a.s.h.ReplicationHandler Poll scheduled at an interval of 1000ms
[junit4] 2> 1578959 INFO (searcherExecutor-6062-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@133d219[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C1)))}
[junit4] 2> 1578959 INFO (Thread-2790) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=18, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6833003385908422]
[junit4] 2> 1578960 INFO (Thread-2790) [ x:collection1] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1578960 INFO (Thread-2790) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@74023e[collection1] main]
[junit4] 2> 1578960 INFO (Thread-2790) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@187ce02
[junit4] 2> 1578960 INFO (searcherExecutor-6062-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@74023e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C1)))}
[junit4] 2> 1579011 INFO (qtp18151319-13719) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/select params={q=*:*&sort=id+desc&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 1579012 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[5E750593413ADA7B]) [ ] o.a.s.h.TestReplicationHandler Waited for 700ms and found 1 docs
[junit4] 2> 1579013 INFO (qtp621347-13706) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 1579015 INFO (qtp621347-13699) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 1579018 INFO (qtp621347-13701) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={slave=false&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=0
[junit4] 2> 1579018 INFO (qtp18151319-13720) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=getDetails&qt=/replication&wt=javabin&version=2&command=details} status=0 QTime=2
[junit4] 2> 1579020 INFO (qtp621347-13702) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 1579022 INFO (qtp18151319-13721) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={_trace=assertVersions&qt=/replication&wt=javabin&version=2&command=indexversion} status=0 QTime=0
[junit4] 2> 1579023 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[5E750593413ADA7B]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestIndexAndConfigReplication
[junit4] 2> 1579024 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[5E750593413ADA7B]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@4ab235{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1579024 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[5E750593413ADA7B]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=10314752
[junit4] 2> 1579025 INFO (coreCloseExecutor-6067-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@40134
[junit4] 2> 1579028 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[5E750593413ADA7B]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1fe0411{/solr,null,UNAVAILABLE}
[junit4] 2> 1579029 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[5E750593413ADA7B]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@17f3567{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1579029 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[5E750593413ADA7B]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=31030272
[junit4] 2> 1579030 INFO (coreCloseExecutor-6069-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@19a37a
[junit4] 2> 1579032 INFO (TEST-TestReplicationHandler.doTestIndexAndConfigReplication-seed#[5E750593413ADA7B]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@19b3182{/solr,null,UNAVAILABLE}
[junit4] 2> 1579035 INFO (SUITE-TestReplicationHandler-seed#[5E750593413ADA7B]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.handler.TestReplicationHandler_5E750593413ADA7B-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {name=PostingsFormat(name=LuceneVarGapDocFreqInterval), id=PostingsFormat(name=LuceneVarGapDocFreqInterval), newname=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{}, maxPointsInLeafNode=766, maxMBSortInHeap=5.979243205727559, sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=sk-SK, timezone=Indian/Mahe
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_102 (32-bit)/cpus=3,threads=1,free=99733736,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestSubQueryTransformerDistrib, HdfsTlogReplayBufferedWhileIndexingTest, SolrXmlInZkTest, BigEndianAscendingWordSerializerTest, SuggesterFSTTest, DistributedExpandComponentTest, TestExceedMaxTermLength, FastVectorHighlighterTest, RuleEngineTest, BasicDistributedZkTest, DistanceUnitsTest, TestFilteredDocIdSet, UniqFieldsUpdateProcessorFactoryTest, TestRecoveryHdfs, TestClassicSimilarityFactory, DirectSolrSpellCheckerTest, TestStressCloudBlindAtomicUpdates, TestComponentsName, JSONWriterTest, ExplicitHLLTest, HdfsDirectoryFactoryTest, ZkCLITest, CdcrRequestHandlerTest, ShardRoutingTest, TestShortCircuitedRequests, TestRTimerTree, TestManagedSchemaThreadSafety, CursorMarkTest, TestFastLRUCache, RequiredFieldsTest, TestCharFilters, ChaosMonkeyNothingIsSafeTest, TestUpdate, DocValuesTest, TestLeaderRecoverFromLogOnStartup, TestCursorMarkWithoutUniqueKey, ChangedSchemaMergeTest, TestJsonFacets, TestOrdValues, FileUtilsTest, TestSolrDeletionPolicy1, TestCryptoKeys, DistributedMLTComponentTest, TestQueryWrapperFilter, TestMacros, TestSolrIndexConfig, TestFastOutputStream, TestSchemaSimilarityResource, TestExtendedDismaxParser, CloneFieldUpdateProcessorFactoryTest, TestNumericTerms32, CollectionsAPIAsyncDistributedZkTest, TestCloudDeleteByQuery, DateFieldTest, SmileWriterTest, TestSmileRequest, PKIAuthenticationIntegrationTest, BlobRepositoryCloudTest, BufferStoreTest, TestXmlQParser, TestRawTransformer, DistribDocExpirationUpdateProcessorTest, TestHighlightDedupGrouping, SparseHLLTest, ZkStateWriterTest, TestFieldCache, BadComponentTest, DeleteReplicaTest, TestDownShardTolerantSearch, TestBinaryResponseWriter, BaseCdcrDistributedZkTest, TestSolrFieldCacheMBean, OverseerTest, OutputWriterTest, TestCloudSchemaless, SystemInfoHandlerTest, SOLR749Test, IndexSchemaRuntimeFieldTest, TestLRUCache, TestLRUStatsCache, SchemaVersionSpecificBehaviorTest, ConnectionManagerTest, TestUninvertingReader, CurrencyFieldXmlFileTest, HdfsRecoveryZkTest, TestIBSimilarityFactory, CloudMLTQParserTest, TolerantUpdateProcessorTest, SimplePostToolTest, PreAnalyzedUpdateProcessorTest, TestRestoreCore, UnloadDistributedZkTest, RankQueryTest, SolrTestCaseJ4Test, TestRandomRequestDistribution, TestSolrConfigHandlerCloud, TestDynamicFieldCollectionResource, SolrCLIZkUtilsTest, TestCollationField, VMParamsZkACLAndCredentialsProvidersTest, TestFieldCacheSortRandom, TestAnalyzedSuggestions, TestRTGBase, RequestLoggingTest, BlockCacheTest, PolyFieldTest, TestSort, TestBM25SimilarityFactory, AnalysisErrorHandlingTest, TestNonDefinedSimilarityFactory, TestDistributedSearch, TestSolrXml, XsltUpdateRequestHandlerTest, TestConfigSets, TlogReplayBufferedWhileIndexingTest, TestWordDelimiterFilterFactory, TestBlendedInfixSuggestions, TestPivotHelperCode, TestSha256AuthenticationProvider, HardAutoCommitTest, AnalysisAfterCoreReloadTest, SpatialFilterTest, LeaderInitiatedRecoveryOnCommitTest, SolrRequestParserTest, CdcrReplicationDistributedZkTest, TestFieldTypeCollectionResource, TestHdfsCloudBackupRestore, TestStressVersions, OutOfBoxZkACLAndCredentialsProvidersTest, ReplaceNodeTest, OverseerTaskQueueTest, CoreAdminCreateDiscoverTest, DocumentBuilderTest, HdfsBasicDistributedZkTest, AtomicUpdatesTest, TestRandomDVFaceting, TestReloadAndDeleteDocs, TestRemoteStreaming, CollectionStateFormat2Test, HdfsNNFailoverTest, CoreMergeIndexesAdminHandlerTest, PingRequestHandlerTest, TestFieldCacheSanityChecker, TestManagedResourceStorage, ZkNodePropsTest, SampleTest, TestStressRecovery, LeaderFailoverAfterPartitionTest, TestElisionMultitermQuery, DistributedFacetPivotSmallAdvancedTest, SpatialRPTFieldTypeTest, CustomCollectionTest, TestPartialUpdateDeduplication, TestHashQParserPlugin, QueryEqualityTest, CheckHdfsIndexTest, LeaderElectionTest, BasicZkTest, RecoveryZkTest, TestReplicationHandler]
[junit4] Completed [328/637 (1!)] on J0 in 87.09s, 15 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 53655 lines...]
[JENKINS] Lucene-Solr-6.x-Windows (32bit/jdk1.8.0_102) - Build #
502 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/502/
Java: 32bit/jdk1.8.0_102 -client -XX:+UseParallelGC
All tests passed
Build Log:
[...truncated 53455 lines...]
-ecj-javadoc-lint-src:
[mkdir] Created dir: C:\Users\jenkins\AppData\Local\Temp\ecj1746300895
[ecj-lint] Compiling 127 source files to C:\Users\jenkins\AppData\Local\Temp\ecj1746300895
[ecj-lint] ----------
[ecj-lint] 1. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 21)
[ecj-lint] import java.util.Collection;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The import java.util.Collection is never used
[ecj-lint] ----------
[ecj-lint] 2. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 22)
[ecj-lint] import java.util.HashSet;
[ecj-lint] ^^^^^^^^^^^^^^^^^
[ecj-lint] The import java.util.HashSet is never used
[ecj-lint] ----------
[ecj-lint] 3. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 24)
[ecj-lint] import java.util.List;
[ecj-lint] ^^^^^^^^^^^^^^
[ecj-lint] The import java.util.List is never used
[ecj-lint] ----------
[ecj-lint] 4. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 25)
[ecj-lint] import java.util.Set;
[ecj-lint] ^^^^^^^^^^^^^
[ecj-lint] The import java.util.Set is never used
[ecj-lint] ----------
[ecj-lint] 5. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 27)
[ecj-lint] import org.apache.lucene.analysis.Analyzer;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The import org.apache.lucene.analysis.Analyzer is never used
[ecj-lint] ----------
[ecj-lint] 6. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 33)
[ecj-lint] import org.apache.lucene.index.IndexableField;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The import org.apache.lucene.index.IndexableField is never used
[ecj-lint] ----------
[ecj-lint] 6 problems (6 errors)
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\build.xml:765: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\build.xml:101: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\build.xml:204: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:2177: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:1992: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:2031: Compile failed; see the compiler error output for details.
Total time: 96 minutes 35 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-6.x-Windows (32bit/jdk1.8.0_102) - Build #
501 - Failure!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Windows/501/
Java: 32bit/jdk1.8.0_102 -server -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.TestDistributedSearch.test
Error Message:
Expected to find shardAddress in the up shard info
Stack Trace:
java.lang.AssertionError: Expected to find shardAddress in the up shard info
at __randomizedtesting.SeedInfo.seed([3EC082A62C50A54C:B694BD7C82ACC8B4]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1162)
at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1103)
at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:963)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1018)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 10768 lines...]
[junit4] Suite: org.apache.solr.TestDistributedSearch
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001\init-core-data-001
[junit4] 2> 149478 INFO (SUITE-TestDistributedSearch-seed#[3EC082A62C50A54C]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-9061)
[junit4] 2> 149479 INFO (SUITE-TestDistributedSearch-seed#[3EC082A62C50A54C]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 149931 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001\tempDir-001\control\cores\collection1
[junit4] 2> 149934 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 149935 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@a086ac{/,null,AVAILABLE}
[junit4] 2> 149937 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.ServerConnector Started ServerConnector@13c0eff{HTTP/1.1,[http/1.1]}{127.0.0.1:54412}
[junit4] 2> 149937 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.Server Started @153934ms
[junit4] 2> 149937 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=54412, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001\tempDir-001\control\cores}
[junit4] 2> 149937 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.3.0
[junit4] 2> 149937 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 149937 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 149938 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-07T16:02:15.725Z
[junit4] 2> 149940 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001\tempDir-001\control\solr.xml
[junit4] 2> 149955 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 149957 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001\tempDir-001\control\cores
[junit4] 2> 149957 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 149959 INFO (coreLoadExecutor-268-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 150011 WARN (coreLoadExecutor-268-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 150012 INFO (coreLoadExecutor-268-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 150031 INFO (coreLoadExecutor-268-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 150101 WARN (coreLoadExecutor-268-thread-1) [ ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 150103 INFO (coreLoadExecutor-268-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 150118 INFO (coreLoadExecutor-268-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 150119 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001\tempDir-001\control\cores\collection1\data\]
[junit4] 2> 150119 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1087051
[junit4] 2> 150120 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8851739321577488]
[junit4] 2> 150136 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 150136 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 150137 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 150137 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 150138 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=80.7333984375, floorSegmentMB=0.296875, forceMergeDeletesPctAllowed=6.7588909394131065, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7801593886887092
[junit4] 2> 150139 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@c81c94[collection1] main]
[junit4] 2> 150139 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 150151 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 150190 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 150190 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 150192 INFO (coreLoadExecutor-268-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1547547323641364480
[junit4] 2> 150192 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 150192 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 150192 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 150192 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 150194 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 150194 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 150195 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 150195 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 150195 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@c81c94[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 150935 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001\tempDir-001\shard0\cores\collection1
[junit4] 2> 150938 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 150938 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f682e5{/,null,AVAILABLE}
[junit4] 2> 150939 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1c558e9{HTTP/1.1,[http/1.1]}{127.0.0.1:54415}
[junit4] 2> 150940 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.Server Started @154936ms
[junit4] 2> 150940 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=54415, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001\tempDir-001\shard0\cores}
[junit4] 2> 150941 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.3.0
[junit4] 2> 150941 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 150941 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 150941 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-07T16:02:16.729Z
[junit4] 2> 150942 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001\tempDir-001\shard0\solr.xml
[junit4] 2> 150955 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 150959 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001\tempDir-001\shard0\cores
[junit4] 2> 150959 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 150960 INFO (coreLoadExecutor-279-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-6.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 151013 WARN (coreLoadExecutor-279-thread-1) [ ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
[junit4] 2> 151014 INFO (coreLoadExecutor-279-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 151032 INFO (coreLoadExecutor-279-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 151111 WARN (coreLoadExecutor-279-thread-1) [ ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 151114 INFO (coreLoadExecutor-279-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 151130 INFO (coreLoadExecutor-279-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1
[junit4] 2> 151130 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001\tempDir-001\shard0\cores\collection1\data\]
[junit4] 2> 151130 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1087051
[junit4] 2> 151131 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=13, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8851739321577488]
[junit4] 2> 151151 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 151151 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 151153 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 151153 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 151154 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=80.7333984375, floorSegmentMB=0.296875, forceMergeDeletesPctAllowed=6.7588909394131065, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7801593886887092
[junit4] 2> 151154 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@15cf491[collection1] main]
[junit4] 2> 151154 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 151163 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 151201 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 151201 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 151202 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 151202 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 151202 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 151202 INFO (coreLoadExecutor-279-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1547547324700426240
[junit4] 2> 151202 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 151203 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 151203 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 151204 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 151204 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 151205 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@15cf491[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 151463 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 151470 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1547547324978298880)} 0 3
[junit4] 2> 151475 INFO (qtp6321792-836) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1547547324985638912)} 0 2
[junit4] 2> 151481 INFO (qtp10412308-820) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1547547324989833216)]} 0 3
[junit4] 2> 151484 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1547547324994027520)]} 0 2
[junit4] 2> 151487 INFO (qtp10412308-821) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1547547324998221824)]} 0 0
[junit4] 2> 151489 INFO (qtp6321792-838) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1547547325000318976)]} 0 0
[junit4] 2> 151490 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1547547325002416128)]} 0 0
[junit4] 2> 151492 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1547547325004513280)]} 0 0
[junit4] 2> 151494 INFO (qtp10412308-823) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1547547325006610432)]} 0 0
[junit4] 2> 151496 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1547547325008707584)]} 0 0
[junit4] 2> 151498 INFO (qtp10412308-824) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1547547325010804736)]} 0 0
[junit4] 2> 151500 INFO (qtp6321792-841) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1547547325012901888)]} 0 0
[junit4] 2> 151502 INFO (qtp10412308-817) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1547547325014999040)]} 0 0
[junit4] 2> 151503 INFO (qtp6321792-834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1547547325016047616)]} 0 0
[junit4] 2> 151505 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1547547325018144768)]} 0 0
[junit4] 2> 151506 INFO (qtp6321792-836) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1547547325019193344)]} 0 0
[junit4] 2> 151507 INFO (qtp10412308-820) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1547547325020241920)]} 0 0
[junit4] 2> 151509 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1547547325022339072)]} 0 0
[junit4] 2> 151510 INFO (qtp10412308-821) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1547547325023387648)]} 0 0
[junit4] 2> 151512 INFO (qtp6321792-838) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1547547325025484800)]} 0 0
[junit4] 2> 151513 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 151513 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1d1f526
[junit4] 2> 151528 INFO (qtp10412308-822) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@279c0d[collection1] main]
[junit4] 2> 151528 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 151529 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@279c0d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c9)))}
[junit4] 2> 151529 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 16
[junit4] 2> 151530 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 151530 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@129eb9e
[junit4] 2> 151535 INFO (qtp6321792-839) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1df51e2[collection1] main]
[junit4] 2> 151536 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 151537 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1df51e2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c9)))}
[junit4] 2> 151537 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 7
[junit4] 2> 151542 INFO (qtp10412308-823) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1547547325053796352)]} 0 3
[junit4] 2> 151547 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1547547325059039232)]} 0 2
[junit4] 2> 151548 INFO (qtp10412308-824) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1547547325063233536)]} 0 0
[junit4] 2> 151550 INFO (qtp6321792-841) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1547547325065330688)]} 0 0
[junit4] 2> 151553 INFO (qtp10412308-817) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1547547325067427840)]} 0 0
[junit4] 2> 151555 INFO (qtp6321792-834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1547547325070573568)]} 0 0
[junit4] 2> 151557 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1547547325072670720)]} 0 0
[junit4] 2> 151558 INFO (qtp6321792-836) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1547547325073719296)]} 0 0
[junit4] 2> 151561 INFO (qtp10412308-820) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1547547325075816448)]} 0 0
[junit4] 2> 151562 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1001 (1547547325077913600)]} 0 0
[junit4] 2> 151564 INFO (qtp10412308-821) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1547547325080010752)]} 0 0
[junit4] 2> 151566 INFO (qtp6321792-838) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1547547325082107904)]} 0 0
[junit4] 2> 151567 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1547547325083156480)]} 0 0
[junit4] 2> 151568 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1547547325084205056)]} 0 0
[junit4] 2> 151570 INFO (qtp10412308-823) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1547547325086302208)]} 0 0
[junit4] 2> 151571 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1547547325087350784)]} 0 0
[junit4] 2> 151572 INFO (qtp10412308-824) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1547547325088399360)]} 0 0
[junit4] 2> 151573 INFO (qtp6321792-841) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1547547325089447936)]} 0 0
[junit4] 2> 151574 INFO (qtp10412308-817) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1547547325090496512)]} 0 0
[junit4] 2> 151575 INFO (qtp6321792-834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1547547325091545088)]} 0 0
[junit4] 2> 151576 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1547547325092593664)]} 0 0
[junit4] 2> 151578 INFO (qtp6321792-836) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1547547325093642240)]} 0 0
[junit4] 2> 151579 INFO (qtp10412308-820) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1547547325095739392)]} 0 0
[junit4] 2> 151580 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1547547325096787968)]} 0 0
[junit4] 2> 151581 INFO (qtp10412308-821) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1547547325097836544)]} 0 0
[junit4] 2> 151582 INFO (qtp6321792-838) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1547547325098885120)]} 0 0
[junit4] 2> 151583 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1547547325099933696)]} 0 0
[junit4] 2> 151584 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1547547325100982272)]} 0 0
[junit4] 2> 151585 INFO (qtp10412308-823) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1547547325102030848)]} 0 0
[junit4] 2> 151586 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1547547325103079424)]} 0 0
[junit4] 2> 151587 INFO (qtp10412308-824) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1547547325104128000)]} 0 0
[junit4] 2> 151588 INFO (qtp6321792-841) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1547547325105176576)]} 0 0
[junit4] 2> 151590 INFO (qtp10412308-817) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1547547325107273728)]} 0 0
[junit4] 2> 151591 INFO (qtp6321792-834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1547547325108322304)]} 0 0
[junit4] 2> 151593 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1547547325109370880)]} 0 0
[junit4] 2> 151594 INFO (qtp6321792-834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1547547325111468032)]} 0 0
[junit4] 2> 151595 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1547547325112516608)]} 0 0
[junit4] 2> 151596 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1547547325113565184)]} 0 0
[junit4] 2> 151597 INFO (qtp10412308-821) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1547547325114613760)]} 0 0
[junit4] 2> 151598 INFO (qtp6321792-838) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1547547325115662336)]} 0 0
[junit4] 2> 151599 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1547547325116710912)]} 0 0
[junit4] 2> 151600 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1547547325117759488)]} 0 0
[junit4] 2> 151602 INFO (qtp10412308-823) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1547547325119856640)]} 0 0
[junit4] 2> 151603 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1547547325120905216)]} 0 0
[junit4] 2> 151604 INFO (qtp10412308-823) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1547547325121953792)]} 0 0
[junit4] 2> 151605 INFO (qtp6321792-841) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1547547325123002368)]} 0 0
[junit4] 2> 151606 INFO (qtp10412308-817) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1547547325124050944)]} 0 0
[junit4] 2> 151607 INFO (qtp6321792-836) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1547547325125099520)]} 0 0
[junit4] 2> 151609 INFO (qtp10412308-820) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1547547325127196672)]} 0 0
[junit4] 2> 151610 INFO (qtp6321792-834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1547547325128245248)]} 0 0
[junit4] 2> 151612 INFO (qtp10412308-820) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1547547325130342400)]} 0 0
[junit4] 2> 151613 INFO (qtp6321792-834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1547547325131390976)]} 0 0
[junit4] 2> 151614 INFO (qtp10412308-821) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1547547325132439552)]} 0 0
[junit4] 2> 151615 INFO (qtp6321792-838) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1547547325133488128)]} 0 0
[junit4] 2> 151616 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1547547325134536704)]} 0 0
[junit4] 2> 151618 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1547547325136633856)]} 0 0
[junit4] 2> 151619 INFO (qtp10412308-824) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1547547325137682432)]} 0 0
[junit4] 2> 151620 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1547547325138731008)]} 0 0
[junit4] 2> 151621 INFO (qtp10412308-823) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1547547325139779584)]} 0 0
[junit4] 2> 151623 INFO (qtp6321792-841) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1547547325141876736)]} 0 0
[junit4] 2> 151624 INFO (qtp10412308-817) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1547547325142925312)]} 0 0
[junit4] 2> 151626 INFO (qtp6321792-836) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1547547325145022464)]} 0 0
[junit4] 2> 151627 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1547547325146071040)]} 0 0
[junit4] 2> 151628 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1547547325147119616)]} 0 0
[junit4] 2> 151629 INFO (qtp10412308-820) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1547547325148168192)]} 0 0
[junit4] 2> 151630 INFO (qtp6321792-834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1547547325149216768)]} 0 0
[junit4] 2> 151631 INFO (qtp10412308-821) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1547547325150265344)]} 0 0
[junit4] 2> 151632 INFO (qtp6321792-838) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1547547325151313920)]} 0 0
[junit4] 2> 151634 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1547547325153411072)]} 0 0
[junit4] 2> 151636 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1547547325155508224)]} 0 0
[junit4] 2> 151638 INFO (qtp10412308-824) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1547547325157605376)]} 0 0
[junit4] 2> 151639 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1547547325158653952)]} 0 0
[junit4] 2> 151640 INFO (qtp10412308-823) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1547547325159702528)]} 0 0
[junit4] 2> 151641 INFO (qtp6321792-841) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1547547325160751104)]} 0 0
[junit4] 2> 151642 INFO (qtp10412308-817) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1547547325161799680)]} 0 0
[junit4] 2> 151643 INFO (qtp6321792-836) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1547547325162848256)]} 0 0
[junit4] 2> 151645 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1547547325164945408)]} 0 0
[junit4] 2> 151646 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1547547325165993984)]} 0 0
[junit4] 2> 151647 INFO (qtp10412308-820) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1547547325167042560)]} 0 0
[junit4] 2> 151648 INFO (qtp6321792-834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1547547325168091136)]} 0 0
[junit4] 2> 151649 INFO (qtp10412308-821) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1547547325169139712)]} 0 0
[junit4] 2> 151650 INFO (qtp6321792-838) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1547547325170188288)]} 0 0
[junit4] 2> 151652 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1547547325172285440)]} 0 0
[junit4] 2> 151653 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1547547325173334016)]} 0 0
[junit4] 2> 151654 INFO (qtp10412308-824) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1547547325174382592)]} 0 0
[junit4] 2> 151655 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1547547325175431168)]} 0 0
[junit4] 2> 151656 INFO (qtp10412308-823) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1547547325176479744)]} 0 0
[junit4] 2> 151658 INFO (qtp6321792-841) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1547547325178576896)]} 0 0
[junit4] 2> 151659 INFO (qtp10412308-817) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1547547325179625472)]} 0 0
[junit4] 2> 151660 INFO (qtp6321792-836) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1547547325180674048)]} 0 0
[junit4] 2> 151663 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1547547325183819776)]} 0 0
[junit4] 2> 151664 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1547547325184868352)]} 0 0
[junit4] 2> 151665 INFO (qtp10412308-820) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1547547325185916928)]} 0 0
[junit4] 2> 151666 INFO (qtp6321792-834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1547547325186965504)]} 0 0
[junit4] 2> 151667 INFO (qtp10412308-821) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1547547325188014080)]} 0 0
[junit4] 2> 151668 INFO (qtp6321792-838) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1547547325189062656)]} 0 0
[junit4] 2> 151669 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1547547325190111232)]} 0 0
[junit4] 2> 151670 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1547547325191159808)]} 0 0
[junit4] 2> 151671 INFO (qtp10412308-824) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1547547325192208384)]} 0 0
[junit4] 2> 151672 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1547547325193256960)]} 0 0
[junit4] 2> 151673 INFO (qtp10412308-824) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1547547325194305536)]} 0 0
[junit4] 2> 151674 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1547547325195354112)]} 0 0
[junit4] 2> 151675 INFO (qtp10412308-824) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1547547325196402688)]} 0 0
[junit4] 2> 151676 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1547547325197451264)]} 0 0
[junit4] 2> 151678 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1547547325198499840)]} 0 0
[junit4] 2> 151679 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1547547325200596992)]} 0 0
[junit4] 2> 151680 INFO (qtp10412308-820) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1547547325201645568)]} 0 0
[junit4] 2> 151681 INFO (qtp6321792-834) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1547547325202694144)]} 0 0
[junit4] 2> 151683 INFO (qtp10412308-821) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1547547325204791296)]} 0 0
[junit4] 2> 151684 INFO (qtp6321792-838) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1547547325205839872)]} 0 0
[junit4] 2> 151685 INFO (qtp10412308-822) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1547547325206888448)]} 0 0
[junit4] 2> 151686 INFO (qtp6321792-839) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1547547325207937024)]} 0 0
[junit4] 2> 151688 INFO (qtp10412308-823) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1547547325210034176)]} 0 0
[junit4] 2> 151689 INFO (qtp6321792-841) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1547547325211082752)]} 0 0
[junit4] 2> 151690 INFO (qtp10412308-817) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1547547325212131328)]} 0 0
[junit4] 2> 151691 INFO (qtp6321792-836) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1547547325213179904)]} 0 0
[junit4] 2> 151693 INFO (qtp10412308-824) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1547547325215277056)]} 0 0
[junit4] 2> 151695 INFO (qtp6321792-840) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1547547325217374208)]} 0 0
[junit4] 2> 151697 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 151698 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1d1f526
[junit4] 2> 151709 INFO (qtp10412308-819) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@453eb6[collection1] main]
[junit4] 2> 151709 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 151710 INFO (searcherExecutor-269-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@453eb6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c9) Uninverting(_1(6.3.0):c59)))}
[junit4] 2> 151710 INFO (qtp10412308-819) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 13
[junit4] 2> 151712 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 151712 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@129eb9e
[junit4] 2> 151722 INFO (qtp6321792-837) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@37db2[collection1] main]
[junit4] 2> 151722 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 151723 INFO (searcherExecutor-280-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@37db2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c9) Uninverting(_1(6.3.0):c59)))}
[junit4] 2> 151723 INFO (qtp6321792-837) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 11
[junit4] 2> 151725 INFO (qtp10412308-820) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 151725 INFO (qtp10412308-820) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 151727 INFO (qtp10412308-821) [ x:collection1] o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be negative
[junit4] 2> 151727 INFO (qtp10412308-821) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 QTime=0
[junit4] 2> 151729 INFO (qtp10412308-822) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151737 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137521&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151753 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137521&ids=11,145,135,14,115,6,117,7,9,10&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 151758 INFO (qtp6321792-834) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:54415//collection1&sort=n_ti1+desc&wt=javabin&version=2} hits=68 status=0 QTime=25
[junit4] 2> 151764 INFO (qtp10412308-823) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151770 INFO (qtp6321792-836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::213]:33332/|http://127.0.0.1:54415//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1475856137555&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151774 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137555&ids=122,1,123,2,15,17,105,138,129,130&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:54415//collection1|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151779 INFO (qtp6321792-841) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:54415//collection1|[ff01::083]:33332/&sort=n_ti1+asc&wt=javabin&version=2} hits=68 status=0 QTime=12
[junit4] 2> 151782 INFO (qtp10412308-823) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151786 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=http://127.0.0.1:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137573&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151789 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137573&ids=12,144,1,134,14,4,104,105,149,119&isShard=true&shard.url=http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151794 INFO (qtp6321792-837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:54415//collection1&sort=n_f1+desc&wt=javabin&version=2} hits=68 status=0 QTime=8
[junit4] 2> 151796 INFO (qtp10412308-824) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151800 INFO (qtp6321792-836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=http://127.0.0.1:54415//collection1|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1475856137586&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151803 INFO (qtp6321792-836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137586&ids=122,146,136,128,129,8,108,109,141,142&isShard=true&shard.url=http://127.0.0.1:54415//collection1|[ff01::213]:33332/|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151805 INFO (qtp6321792-834) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:54415//collection1|[ff01::213]:33332/|[ff01::114]:33332/&sort=n_f1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 151806 INFO (qtp10412308-819) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151811 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137598&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151813 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137598&ids=121,101,148,5,118,119,9,109,140,141&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151816 INFO (qtp6321792-841) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:54415//collection1&sort=n_tf1+desc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 151818 INFO (qtp10412308-820) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151822 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=http://127.0.0.1:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137608&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151824 INFO (qtp6321792-836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137608&ids=144,4,16,115,127,128,117,7,108,10&isShard=true&shard.url=http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151826 INFO (qtp6321792-837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:54415//collection1&sort=n_tf1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 151828 INFO (qtp10412308-821) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151832 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137619&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151834 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137619&ids=121,123,134,113,104,17,149,117,106,120&isShard=true&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151836 INFO (qtp6321792-834) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1&sort=n_d1+desc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 151839 INFO (qtp10412308-822) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151842 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=http://127.0.0.1:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137629&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151845 INFO (qtp6321792-836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137629&ids=1,13,112,145,136,16,6,116,1001,118&isShard=true&shard.url=http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151847 INFO (qtp6321792-841) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:54415//collection1&sort=n_d1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 151848 INFO (qtp10412308-817) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151851 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=http://127.0.0.1:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137638&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151853 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137638&ids=12,14,115,148,104,6,127,117,129,109&isShard=true&shard.url=http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151854 INFO (qtp6321792-837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:54415//collection1&sort=n_td1+desc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 151857 INFO (qtp10412308-817) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151860 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:54415//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1475856137647&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151863 INFO (qtp6321792-836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137647&ids=132,11,111,102,16,17,106,128,119,9&isShard=true&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:54415//collection1|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151865 INFO (qtp6321792-834) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:54415//collection1|[ff01::083]:33332/&sort=n_td1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 151867 INFO (qtp10412308-824) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151871 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=http://127.0.0.1:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137658&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151873 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137658&ids=132,12,2,101,123,136,104,106,128,129&isShard=true&shard.url=http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151874 INFO (qtp6321792-841) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:54415//collection1&sort=n_l1+desc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 151876 INFO (qtp10412308-819) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151880 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=http://127.0.0.1:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137667&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151882 INFO (qtp6321792-836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137667&ids=143,133,1,113,146,102,114,138,107,141&isShard=true&shard.url=http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151883 INFO (qtp6321792-837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:54415//collection1&sort=n_l1+asc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 151885 INFO (qtp10412308-820) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151888 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=http://127.0.0.1:54415//collection1|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1475856137675&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151891 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137675&ids=132,11,112,135,103,147,125,127,108,130&isShard=true&shard.url=http://127.0.0.1:54415//collection1|[ff01::213]:33332/|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151892 INFO (qtp6321792-834) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:54415//collection1|[ff01::213]:33332/|[ff01::114]:33332/&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 151894 INFO (qtp10412308-821) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151898 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=http://127.0.0.1:54415//collection1|[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1475856137684&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151899 INFO (qtp6321792-836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137684&ids=121,100,144,124,146,5,116,117,109,10&isShard=true&shard.url=http://127.0.0.1:54415//collection1|[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151900 INFO (qtp6321792-841) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:54415//collection1|[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 151902 INFO (qtp10412308-822) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151907 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:54415//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1475856137693&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151915 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137693&ids=144,134,145,124,14,15,137,7,1001,109&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:54415//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 151916 INFO (qtp6321792-836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:54415//collection1|[ff01::213]:33332/&sort=n_dt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=11
[junit4] 2> 151921 INFO (qtp10412308-822) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151926 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+asc&shard.url=http://127.0.0.1:54415//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1475856137711&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151928 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137711&ids=11,1,4,115,104,5,126,6,139,130&isShard=true&shard.url=http://127.0.0.1:54415//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151929 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:54415//collection1|[ff01::213]:33332/&sort=n_dt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 151932 INFO (qtp10412308-822) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151935 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+desc&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137722&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151937 INFO (qtp6321792-836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137722&ids=11,121,132,122,112,5,6,105,9,109&isShard=true&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151937 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 151940 INFO (qtp10412308-822) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151945 INFO (qtp6321792-837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+asc&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1475856137731&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151948 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137731&ids=110,143,101,14,135,115,116,128,106,120&isShard=true&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151949 INFO (qtp6321792-836) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1|[ff01::114]:33332/&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 151952 INFO (qtp10412308-822) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=a_i1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151957 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:54415//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1475856137743&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151959 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137743&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:54415//collection1|[ff01::114]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151960 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:54415//collection1|[ff01::114]:33332/&sort=a_i1+desc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 151974 INFO (qtp10412308-820) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&version=2} hits=68 status=0 QTime=11
[junit4] 2> 151977 INFO (qtp6321792-837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort={!func}testfunc(add(a_i1,5))+desc&shard.url=http://127.0.0.1:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137764&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151978 INFO (qtp6321792-837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137764&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151979 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=http://127.0.0.1:54415//collection1&sort={!func}testfunc(add(a_i1,5))+desc&wt=javabin&version=2} hits=68 status=0 QTime=3
[junit4] 2> 151982 INFO (qtp10412308-821) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=a_i1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151985 INFO (qtp6321792-841) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i1+asc&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137771&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151988 INFO (qtp6321792-841) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137771&ids=11,12,1,13,2,3,4,6,7,9&isShard=true&shard.url=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151989 INFO (qtp6321792-837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:54415//collection1&sort=a_i1+asc&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 151991 INFO (qtp10412308-821) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=*,score&sort=a_i1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 151997 INFO (qtp6321792-834) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=a_i1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:54415//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1475856137781&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 151998 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1475856137781&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:54415//collection1|[ff01::083]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 151999 INFO (qtp6321792-841) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:54415//collection1|[ff01::083]:33332/&fl=*,score&sort=a_i1+desc&wt=javabin&version=2} hits=68 status=0 QTime=6
[junit4] 2> 152001 INFO (qtp10412308-821) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=*,score&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 152005 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:54415//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1475856137792&isShard=true&wt=javabin} hits=68 status=0 QTime=0
[junit4] 2> 152007 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&fl=*,score&shards.purpose=64&NOW=1475856137792&ids=121,100,144,124,146,5,116,117,109,10&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:54415//collection1|[ff01::213]:33332/&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 152007 INFO (qtp6321792-839) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:54415//collection1|[ff01::213]:33332/&fl=*,score&sort=n_tl1+asc&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 152010 INFO (qtp10412308-821) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 152037 INFO (qtp6321792-834) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:54415//collection1&rows=10&version=2&q=*:*&NOW=1475856137800&isShard=true&wt=javabin} hits=68 status=0 QTime=1
[junit4] 2> 152038 INFO (qtp6321792-834) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&distrib=false&shards.purpose=64&NOW=1475856137800&ids=132,11,112,135,103,147,125,127,108,130&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 152039 INFO (qtp6321792-837) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:54415//collection1&sort=n_tl1+desc&wt=javabin&version=2} hits=68 status=0 QTime=27
[junit4] 2> 152047 INFO (qtp10412308-821) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q={!func}a_i1&distrib=false&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 152050 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q={!func}a_i1&distrib=false&fl=id&fl=score&shards.purpose=4&NOW=1475856137836&start=0&fsv=true&isShard=true&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 152052 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q={!func}a_i1&distrib=false&shards.purpose=64&NOW=1475856137836&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 152053 INFO (qtp6321792-834) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q={!func}a_i1&shards=[ff01::114]:33332/|[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:54415//collection1&wt=javabin&version=2} hits=68 status=0 QTime=5
[junit4] 2> 152055 INFO (qtp10412308-822) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q={!func}a_i1&distrib=false&fl=*,score&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 152060 INFO (qtp6321792-841) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q={!func}a_i1&distrib=false&fl=id&fl=score&shards.purpose=4&NOW=1475856137846&start=0&fsv=true&isShard=true&shard.url=http://127.0.0.1:54415//collection1&rows=10&wt=javabin&version=2} hits=68 status=0 QTime=0
[junit4] 2> 152062 INFO (qtp6321792-841) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q={!func}a_i1&distrib=false&fl=*,score&shards.purpose=64&NOW=1475856137846&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=http://127.0.0.1:54415//collection1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 152062 INFO (qtp6321792-838) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q={!func}a_i1&shards=http://127.0.0.1:54415//collection1&fl=*,score&wt=javabin&version=2} hits=68 status=0 QTime=4
[junit4] 2> 152066 INFO (qtp10412308-822) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=quick&distrib=false&wt=javabin&version=2} hits=2 status=0 QTime=0
[junit4] 2> 152072 INFO (qtp6321792-840) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=quick&distrib=false&fl=id&fl=score&shards.purpose=4&NOW=1475856137856&start=0&fsv=true&isShard=true&shard.url=[ff01::213]:3
[...truncated too long message...]
it4] 2> 237682 INFO (qtp6998880-941) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id,a_i1,id&group.limit=10&shards.purpose=64&shards.info=true&shard.url=http://127.0.0.1:54648//collection1&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards.tolerant=true&NOW=1475856223438&ids=13&isShard=true&timeAllowed=1&wt=javabin} status=0 QTime=0
[junit4] 2> 237682 INFO (qtp6362208-955) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={distrib=false&fl=id,a_i1,id&group.limit=10&shards.purpose=64&shards.info=true&shard.url=http://127.0.0.1:54651//collection1&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards.tolerant=true&NOW=1475856223438&ids=8&isShard=true&timeAllowed=1&wt=javabin} status=0 QTime=0
[junit4] 2> 237689 INFO (qtp8288168-972) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/select params={fl=id,a_i1&group.limit=10&sort=a_i1+asc,+id+asc&shards.info=true&rows=100&group.query=a_t:kings+OR+a_t:eggs&version=2&q=*:*&shards=http://127.0.0.1:54648//collection1,http://127.0.0.1:54651//collection1,[ff01::213]:33332/|http://127.0.0.1:54655//collection1&shards.tolerant=true&timeAllowed=1&wt=javabin&group=true} status=0 QTime=38
[junit4] 2> 237689 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.SolrTestCaseJ4 ###Ending test
[junit4] 2> 237691 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@b8086d{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 237691 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1522571
[junit4] 2> 237692 INFO (coreCloseExecutor-373-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@149ce86
[junit4] 2> 237714 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2dd1b3{/,null,UNAVAILABLE}
[junit4] 2> 237716 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@1383e09{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 237716 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=23321134
[junit4] 2> 237717 INFO (coreCloseExecutor-375-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@f2ce12
[junit4] 2> 237734 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4161bb{/,null,UNAVAILABLE}
[junit4] 2> 237737 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@16324f3{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 237737 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=18592033
[junit4] 2> 237738 INFO (coreCloseExecutor-377-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1c0788f
[junit4] 2> 237752 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@9a3971{/,null,UNAVAILABLE}
[junit4] 2> 237754 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@12c5468{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 237754 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=26497352
[junit4] 2> 237756 INFO (coreCloseExecutor-379-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1396395
[junit4] 2> 237770 INFO (TEST-TestDistributedSearch.test-seed#[3EC082A62C50A54C]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1eb7ab7{/,null,UNAVAILABLE}
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedSearch -Dtests.method=test -Dtests.seed=3EC082A62C50A54C -Dtests.slow=true -Dtests.locale=mk -Dtests.timezone=America/Whitehorse -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 88.3s J0 | TestDistributedSearch.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: Expected to find shardAddress in the up shard info
[junit4] > at __randomizedtesting.SeedInfo.seed([3EC082A62C50A54C:B694BD7C82ACC8B4]:0)
[junit4] > at org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1162)
[junit4] > at org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1103)
[junit4] > at org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:963)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1018)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 237780 INFO (SUITE-TestDistributedSearch-seed#[3EC082A62C50A54C]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\solr\build\solr-core\test\J0\temp\solr.TestDistributedSearch_3EC082A62C50A54C-001
[junit4] 2> NOTE: test params are: codec=Lucene62, sim=ClassicSimilarity, locale=mk, timezone=America/Whitehorse
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_102 (32-bit)/cpus=3,threads=2,free=70200120,total=205520896
[junit4] 2> NOTE: All tests run in this JVM: [TestPseudoReturnFields, TestStressCloudBlindAtomicUpdates, SyncSliceTest, TestGraphMLResponseWriter, HLLSerializationTest, SynonymTokenizerTest, ConnectionManagerTest, TestElisionMultitermQuery, TestMaxScoreQueryParser, TestSolrJ, HdfsWriteToMultipleCollectionsTest, ZkControllerTest, SecurityConfHandlerTest, TestStressLucene, TestDownShardTolerantSearch, TestDistributedSearch]
[junit4] Completed [36/637 (1!)] on J0 in 88.34s, 1 test, 1 failure <<< FAILURES!
[...truncated 42681 lines...]
-ecj-javadoc-lint-src:
[mkdir] Created dir: C:\Users\jenkins\AppData\Local\Temp\ecj1366159887
[ecj-lint] Compiling 127 source files to C:\Users\jenkins\AppData\Local\Temp\ecj1366159887
[ecj-lint] ----------
[ecj-lint] 1. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 21)
[ecj-lint] import java.util.Collection;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The import java.util.Collection is never used
[ecj-lint] ----------
[ecj-lint] 2. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 22)
[ecj-lint] import java.util.HashSet;
[ecj-lint] ^^^^^^^^^^^^^^^^^
[ecj-lint] The import java.util.HashSet is never used
[ecj-lint] ----------
[ecj-lint] 3. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 24)
[ecj-lint] import java.util.List;
[ecj-lint] ^^^^^^^^^^^^^^
[ecj-lint] The import java.util.List is never used
[ecj-lint] ----------
[ecj-lint] 4. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 25)
[ecj-lint] import java.util.Set;
[ecj-lint] ^^^^^^^^^^^^^
[ecj-lint] The import java.util.Set is never used
[ecj-lint] ----------
[ecj-lint] 5. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 27)
[ecj-lint] import org.apache.lucene.analysis.Analyzer;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The import org.apache.lucene.analysis.Analyzer is never used
[ecj-lint] ----------
[ecj-lint] 6. ERROR in C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\benchmark\src\java\org\apache\lucene\benchmark\byTask\tasks\ReadTask.java (at line 33)
[ecj-lint] import org.apache.lucene.index.IndexableField;
[ecj-lint] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[ecj-lint] The import org.apache.lucene.index.IndexableField is never used
[ecj-lint] ----------
[ecj-lint] 6 problems (6 errors)
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\build.xml:765: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\build.xml:101: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\build.xml:204: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:2177: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:1992: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-6.x-Windows\lucene\common-build.xml:2031: Compile failed; see the compiler error output for details.
Total time: 86 minutes 6 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any