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 2019/07/08 01:55:06 UTC
[JENKINS-EA] Lucene-Solr-master-Windows (64bit/jdk-13-ea+26) -
Build # 8041 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8041/
Java: 64bit/jdk-13-ea+26 -XX:+UseCompressedOops -XX:+UseSerialGC
2 tests failed.
FAILED: org.apache.solr.handler.TestReplicationHandlerBackup.doTestBackup
Error Message:
Test abandoned because suite timeout was reached.
Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
at __randomizedtesting.SeedInfo.seed([5BED2723F2025943]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.handler.TestReplicationHandlerBackup
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([5BED2723F2025943]:0)
Build Log:
[...truncated 15486 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandlerBackup
[junit4] 2> 1008155 INFO (SUITE-TestReplicationHandlerBackup-seed#[5BED2723F2025943]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001\init-core-data-001
[junit4] 2> 1008156 INFO (SUITE-TestReplicationHandlerBackup-seed#[5BED2723F2025943]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1008158 INFO (SUITE-TestReplicationHandlerBackup-seed#[5BED2723F2025943]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="None")
[junit4] 2> 1008161 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBackupOnCommit
[junit4] 2> 1008162 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001\solr-instance-001\collection1
[junit4] 2> 1008198 WARN (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1008198 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1008198 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1008198 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 13-ea+26
[junit4] 2> 1008198 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1008198 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1008198 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1008199 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@feec683{/solr,null,AVAILABLE}
[junit4] 2> 1008199 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@49f6e08e{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:64936}
[junit4] 2> 1008199 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.e.j.s.Server Started @1026359ms
[junit4] 2> 1008199 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001\solr-instance-001\collection1\data, hostPort=64936}
[junit4] 2> 1008201 ERROR (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1008201 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1008201 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 1008201 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1008201 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1008201 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-07-07T23:02:56.230042700Z
[junit4] 2> 1008201 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001\solr-instance-001\solr.xml
[junit4] 2> 1008204 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1008204 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1008205 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@10fedc05, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1008705 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1008708 WARN (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@44f427f0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1008708 WARN (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@44f427f0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1008714 WARN (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@58ca6231[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1008714 WARN (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@58ca6231[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1008760 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1008760 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1008786 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10fedc05
[junit4] 2> 1008804 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10fedc05
[junit4] 2> 1008804 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10fedc05
[junit4] 2> 1008807 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001\solr-instance-001\.
[junit4] 2> 1008808 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1008838 INFO (coreLoadExecutor-3672-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1008848 INFO (coreLoadExecutor-3672-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1008849 INFO (coreLoadExecutor-3672-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1008849 INFO (coreLoadExecutor-3672-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001\solr-instance-001\.\collection1, trusted=true
[junit4] 2> 1008849 INFO (coreLoadExecutor-3672-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10fedc05
[junit4] 2> 1008849 INFO (coreLoadExecutor-3672-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001\solr-instance-001\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001\solr-instance-001\.\collection1\data\]
[junit4] 2> 1008859 INFO (coreLoadExecutor-3672-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=71.91015625, floorSegmentMB=1.6748046875, forceMergeDeletesPctAllowed=15.201288487773056, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7822883403649447, deletesPctAllowed=27.3522814101138
[junit4] 2> 1008986 INFO (coreLoadExecutor-3672-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1008986 INFO (coreLoadExecutor-3672-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1008986 INFO (coreLoadExecutor-3672-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=574887116, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1008986 INFO (coreLoadExecutor-3672-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4b496fcc[collection1] main]
[junit4] 2> 1008986 INFO (coreLoadExecutor-3672-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001\solr-instance-001\collection1\conf
[junit4] 2> 1008986 INFO (coreLoadExecutor-3672-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema-replication2.xml:schema.xml
[junit4] 2> 1008986 INFO (coreLoadExecutor-3672-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1008995 INFO (searcherExecutor-3673-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4b496fcc[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1008997 INFO (qtp1495287419-10702) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1008997 INFO (qtp1495287419-10702) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 6
[junit4] 2> 1008997 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.h.BackupRestoreUtils Indexing 81 test docs
[junit4] 2> 1009009 INFO (qtp1495287419-10702) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9, ... (81 adds)]} 0 9
[junit4] 2> 1009013 INFO (qtp1495287419-10702) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1009013 INFO (qtp1495287419-10702) [ x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@57c65965 commitCommandVersion:0
[junit4] 2> 1009029 INFO (Thread-1520) [ ] o.a.s.h.SnapShooter Creating backup snapshot <not named> at file:///C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001/solr-instance-001/./collection1/data/
[junit4] 2> 1009036 INFO (qtp1495287419-10702) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5d3c2178[collection1] main]
[junit4] 2> 1009036 INFO (searcherExecutor-3673-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5d3c2178[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c81:[diagnostics={lucene.version=9.0.0, source=flush, os.arch=amd64, java.runtime.version=13-ea+26, os.version=10.0, os=Windows 10, java.vendor=Oracle Corporation, timestamp=1562540577044, java.version=13-ea, java.vm.version=13-ea+26}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1009036 INFO (qtp1495287419-10702) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1009036 INFO (qtp1495287419-10702) [ 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 37
[junit4] 2> 1009059 INFO (qtp1495287419-10704) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=23
[junit4] 2> 1009070 INFO (Thread-1520) [ ] o.a.s.h.SnapShooter Done creating backup snapshot: <not named> at file:///C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001/solr-instance-001/./collection1/data/
[junit4] 2> 1009739 INFO (qtp1495287419-10706) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1010439 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testBackupOnCommit
[junit4] 2> 1010440 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1219818005
[junit4] 2> 1010441 INFO (coreCloseExecutor-3677-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@9afd7a7
[junit4] 2> 1010441 INFO (coreCloseExecutor-3677-thread-1) [ x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=9afd7a7
[junit4] 2> 1010441 INFO (coreCloseExecutor-3677-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@ef51c4d: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2671fcfc
[junit4] 2> 1010469 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1010469 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7661b6b9: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@4f7e969f
[junit4] 2> 1010469 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1010469 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@336a68de: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1b5fba71
[junit4] 2> 1010469 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1010469 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5abd4d91: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@abd1a5c
[junit4] 2> 1010478 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@49f6e08e{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 1010478 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@feec683{/solr,null,UNAVAILABLE}
[junit4] 2> 1010478 INFO (TEST-TestReplicationHandlerBackup.testBackupOnCommit-seed#[5BED2723F2025943]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1010486 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.SolrTestCaseJ4 ###Starting doTestBackup
[junit4] 2> 1010489 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001\solr-instance-002\collection1
[junit4] 2> 1010501 WARN (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 1010501 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 1010501 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1010501 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 13-ea+26
[junit4] 2> 1010501 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1010501 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1010501 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1010501 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@11ec1f67{/solr,null,AVAILABLE}
[junit4] 2> 1010503 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6e619193{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:64948}
[junit4] 2> 1010503 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.e.j.s.Server Started @1029724ms
[junit4] 2> 1010503 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001\solr-instance-002\collection1\data, hostPort=64948}
[junit4] 2> 1010504 ERROR (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1010504 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1010504 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 1010504 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1010504 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1010504 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-07-07T23:02:58.533160800Z
[junit4] 2> 1010505 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001\solr-instance-002\solr.xml
[junit4] 2> 1010509 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1010509 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1010510 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@10fedc05, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1010816 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1010820 WARN (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2f366e99[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1010820 WARN (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2f366e99[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1010825 WARN (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@314f9827[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1010825 WARN (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@314f9827[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1010886 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1010887 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1010922 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10fedc05
[junit4] 2> 1010935 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10fedc05
[junit4] 2> 1010935 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10fedc05
[junit4] 2> 1010940 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001\solr-instance-002\.
[junit4] 2> 1010940 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1010963 INFO (coreLoadExecutor-3687-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1010987 INFO (coreLoadExecutor-3687-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1010989 INFO (coreLoadExecutor-3687-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.2 with uniqueid field id
[junit4] 2> 1010989 INFO (coreLoadExecutor-3687-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001\solr-instance-002\.\collection1, trusted=true
[junit4] 2> 1010990 INFO (coreLoadExecutor-3687-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10fedc05
[junit4] 2> 1010990 INFO (coreLoadExecutor-3687-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001\solr-instance-002\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001\solr-instance-002\.\collection1\data\]
[junit4] 2> 1010991 INFO (coreLoadExecutor-3687-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=71.91015625, floorSegmentMB=1.6748046875, forceMergeDeletesPctAllowed=15.201288487773056, segmentsPerTier=30.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7822883403649447, deletesPctAllowed=27.3522814101138
[junit4] 2> 1011096 INFO (coreLoadExecutor-3687-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1011096 INFO (coreLoadExecutor-3687-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1011099 INFO (coreLoadExecutor-3687-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=574887116, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 1011099 INFO (coreLoadExecutor-3687-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3cfc9cd1[collection1] main]
[junit4] 2> 1011099 INFO (coreLoadExecutor-3687-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001\solr-instance-002\collection1\conf
[junit4] 2> 1011100 INFO (coreLoadExecutor-3687-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Replication enabled for following config files: schema-replication2.xml:schema.xml
[junit4] 2> 1011100 INFO (coreLoadExecutor-3687-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1011102 INFO (searcherExecutor-3688-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3cfc9cd1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1011480 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1011481 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 1
[junit4] 2> 1011482 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.h.BackupRestoreUtils Indexing 22 test docs
[junit4] 2> 1011485 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0, 1, 2, 3, 4, 5, 6, 7, 8, 9, ... (22 adds)]} 0 4
[junit4] 2> 1011486 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1011486 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@30aee5ac commitCommandVersion:0
[junit4] 2> 1011495 INFO (Thread-1530) [ ] o.a.s.h.SnapShooter Creating backup snapshot <not named> at file:///C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001/solr-instance-002/./collection1/data/
[junit4] 2> 1011495 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1d1b3b78[collection1] main]
[junit4] 2> 1011497 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1011497 INFO (searcherExecutor-3688-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1d1b3b78[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):c22:[diagnostics={lucene.version=9.0.0, source=flush, os.arch=amd64, java.runtime.version=13-ea+26, os.version=10.0, os=Windows 10, java.vendor=Oracle Corporation, timestamp=1562540579521, java.version=13-ea, java.vm.version=13-ea+26}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}])))}
[junit4] 2> 1011497 INFO (qtp1427124160-10733) [ 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 14
[junit4] 2> 1011499 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1011526 INFO (Thread-1530) [ ] o.a.s.h.SnapShooter Done creating backup snapshot: <not named> at file:///C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001/solr-instance-002/./collection1/data/
[junit4] 2> 1012255 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1013012 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={name=lujjwjkmiweev&wt=xml&command=backup} status=0 QTime=1
[junit4] 2> 1013012 INFO (Thread-1531) [ ] o.a.s.h.SnapShooter Creating backup snapshot lujjwjkmiweev at file:///C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001/solr-instance-002/./collection1/data/
[junit4] 2> 1013022 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1013023 INFO (Thread-1531) [ ] o.a.s.h.SnapShooter Done creating backup snapshot: lujjwjkmiweev at file:///C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001/solr-instance-002/./collection1/data/
[junit4] 2> 1013778 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={name=zjqspnq&wt=xml&command=backup} status=0 QTime=1
[junit4] 2> 1013778 INFO (Thread-1532) [ ] o.a.s.h.SnapShooter Creating backup snapshot zjqspnq at file:///C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001/solr-instance-002/./collection1/data/
[junit4] 2> 1013782 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1013788 INFO (Thread-1532) [ ] o.a.s.h.SnapShooter Done creating backup snapshot: zjqspnq at file:///C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001/solr-instance-002/./collection1/data/
[junit4] 2> 1014545 INFO (Thread-1533) [ ] o.a.s.h.SnapShooter Creating backup snapshot gwmmlfej at file:///C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001/solr-instance-002/./collection1/data/
[junit4] 2> 1014549 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={name=gwmmlfej&wt=xml&command=backup} status=0 QTime=7
[junit4] 2> 1014550 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=1
[junit4] 2> 1014571 INFO (Thread-1533) [ ] o.a.s.h.SnapShooter Done creating backup snapshot: gwmmlfej at file:///C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001/solr-instance-002/./collection1/data/
[junit4] 2> 1015304 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={name=haotdxowueebeo&wt=xml&command=backup} status=0 QTime=1
[junit4] 2> 1015304 INFO (Thread-1534) [ ] o.a.s.h.SnapShooter Creating backup snapshot haotdxowueebeo at file:///C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001/solr-instance-002/./collection1/data/
[junit4] 2> 1015305 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1015318 INFO (Thread-1534) [ ] o.a.s.h.SnapShooter Done creating backup snapshot: haotdxowueebeo at file:///C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/build/solr-core/test/J1/temp/solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001/solr-instance-002/./collection1/data/
[junit4] 2> 1016056 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={name=lujjwjkmiweev&wt=xml&command=deletebackup} status=0 QTime=2
[junit4] 2> 1016056 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1016056 INFO (Thread-1535) [ ] o.a.s.h.SnapShooter Deleting snapshot: lujjwjkmiweev
[junit4] 2> 1016206 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1016959 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={name=zjqspnq&wt=xml&command=deletebackup} status=0 QTime=8
[junit4] 2> 1016959 INFO (Thread-1536) [ ] o.a.s.h.SnapShooter Deleting snapshot: zjqspnq
[junit4] 2> 1016963 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1017116 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1017266 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1017417 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1017569 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1017720 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1017881 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1018047 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1018214 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1018381 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1018548 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1018716 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1018883 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=1
[junit4] 2> 1019049 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1019217 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1019386 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=2
[junit4] 2> 1019550 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1019717 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1019884 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1020054 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1020220 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1020386 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1020551 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1020716 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1020882 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1021048 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1021215 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1021382 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=1
[junit4] 2> 1021548 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1021716 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1021882 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1022048 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1022214 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1022380 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1022547 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1022712 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1022877 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1023043 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1023210 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1023376 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1023540 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1023707 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1023872 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1024038 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1024204 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1024370 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1024537 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1024703 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1024870 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1025036 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1025202 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1025369 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1025534 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1025700 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1025865 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1026037 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1026219 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1026401 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1026588 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1026774 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=2
[junit4] 2> 1026956 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1027138 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1027322 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=1
[junit4] 2> 1027506 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1027689 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1027870 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1028052 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1028235 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1028427 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1028609 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1028792 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1028974 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1029157 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1029346 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=4
[junit4] 2> 1029530 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1029712 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1029895 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1030108 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=34
[junit4] 2> 1030291 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1030473 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1030656 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1030840 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=1
[junit4] 2> 1031021 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1031205 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1031388 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1031573 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1031757 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1031939 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1032122 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1032305 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1032487 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1032669 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1032851 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1033033 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1033216 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1033400 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1033581 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1033765 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1033947 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1034131 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1034313 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1034496 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1034679 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=1
[junit4] 2> 1034862 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1035047 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1035248 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1035449 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1035650 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1035850 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1036058 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=1
[junit4] 2> 1036260 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1036460 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1036663 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1036863 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1037062 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1037264 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1037465 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1037664 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1037866 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=1
[junit4] 2> 1038070 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1038271 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1038472 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1038671 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1038875 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1039076 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1039276 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1039476 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1039676 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1039877 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1040077 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1040277 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1040477 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1040678 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1040879 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1041080 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1041279 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1041480 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1041679 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1041880 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1042082 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1042283 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1042484 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1042685 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1042885 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1043086 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1043287 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1043488 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1043688 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1043893 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1044092 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1044294 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1044494 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1044693 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1044894 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1045107 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1045332 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=1
[junit4] 2> 1045554 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1045894 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=110
[junit4] 2> 1046152 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=32
[junit4] 2> 1046379 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=5
[junit4] 2> 1046599 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1046819 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1047040 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1047262 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1047480 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1047701 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1047922 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1048143 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1048363 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1048583 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1048803 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1049024 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1049245 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1049465 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1049686 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1049906 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1050127 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1050347 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1050567 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1050787 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1051008 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1051228 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1051447 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1051668 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1051889 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1052110 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1052331 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1052552 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1052771 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1052992 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1053213 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1053434 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1053654 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1053875 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1054095 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1054314 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1054535 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1054755 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1054977 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1055197 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1055416 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1055637 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1055856 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1056096 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1056339 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1056590 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1056832 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1057075 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1057319 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1057560 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1057802 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1058046 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1058290 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=1
[junit4] 2> 1058533 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1058776 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1059018 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1059261 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1059502 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1059745 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1059987 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1060230 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1060475 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=2
[junit4] 2> 1060717 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1060960 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1061203 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1061444 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1061686 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1061928 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1062171 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1062414 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1062655 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1062898 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1063141 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1063384 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1063626 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1063868 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1064110 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1064354 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=2
[junit4] 2> 1064595 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1064838 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1065081 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1065322 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1065582 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=13
[junit4] 2> 1065832 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=5
[junit4] 2> 1066074 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1066315 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1066557 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1066800 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=1
[junit4] 2> 1067046 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=1
[junit4] 2> 1067288 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1067530 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1067777 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1068029 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1068296 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1068566 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1068833 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1069123 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=18
[junit4] 2> 1069390 INFO (qtp1427124160-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1069655 INFO (qtp1427124160-10728) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1069921 INFO (qtp1427124160-10732) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1070188 INFO (qtp1427124160-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1070455 INFO (qtp1427124160-10730) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTime=0
[junit4] 2> 1070721 INFO (qtp1427124160-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/solr path=/replication params={wt=xml&command=details} status=0 QTim
[...truncated too long message...]
p=TGRP-TestReplicationHandlerBackup]
[junit4] 2> 5) Thread[id=10743, name=Thread-1529, state=WAITING, group=TGRP-TestReplicationHandlerBackup]
[junit4] 2> 6) Thread[id=10734, name=qtp1427124160-10734, state=TIMED_WAITING, group=TGRP-TestReplicationHandlerBackup]
[junit4] 2> 7) Thread[id=10699, name=TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943], state=TIMED_WAITING, group=TGRP-TestReplicationHandlerBackup]
[junit4] 2> 8) Thread[id=10732, name=qtp1427124160-10732, state=TIMED_WAITING, group=TGRP-TestReplicationHandlerBackup]
[junit4] 2> 9) Thread[id=10729, name=qtp1427124160-10729-acceptor-0@e619489-ServerConnector@6e619193{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:64948}, state=RUNNABLE, group=TGRP-TestReplicationHandlerBackup]
[junit4] 2> 10) Thread[id=10730, name=qtp1427124160-10730, state=TIMED_WAITING, group=TGRP-TestReplicationHandlerBackup]
[junit4] 2> 11) Thread[id=10731, name=qtp1427124160-10731, state=TIMED_WAITING, group=TGRP-TestReplicationHandlerBackup]
[junit4] 2> 12) Thread[id=10727, name=qtp1427124160-10727, state=RUNNABLE, group=TGRP-TestReplicationHandlerBackup]
[junit4] 2> 13) Thread[id=10741, name=MetricsHistoryHandler-3686-thread-1, state=TIMED_WAITING, group=TGRP-TestReplicationHandlerBackup]
[junit4] 2> 14) Thread[id=10748, name=Connector-Scheduler-6e619193, state=TIMED_WAITING, group=TGRP-TestReplicationHandlerBackup]
[junit4] 2> 15) Thread[id=10757, name=SolrRrdBackendFactory-3685-thread-2, state=TIMED_WAITING, group=TGRP-TestReplicationHandlerBackup]
[junit4] 2> 16) Thread[id=10739, name=h2sc-3881-thread-1, state=RUNNABLE, group=TGRP-TestReplicationHandlerBackup]
[junit4] 2> 17) Thread[id=10740, name=SolrRrdBackendFactory-3685-thread-1, state=WAITING, group=TGRP-TestReplicationHandlerBackup]
[junit4] 2> 18) Thread[id=10728, name=qtp1427124160-10728, state=TIMED_WAITING, group=TGRP-TestReplicationHandlerBackup]
[junit4] 2> 8218229 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.SolrTestCaseJ4 ###Ending doTestBackup
[junit4] 2> 8218229 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=587656455
[junit4] 2> 8218230 INFO (coreCloseExecutor-3692-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@729b21ae
[junit4] 2> 8218230 INFO (coreCloseExecutor-3692-thread-1) [ x:collection1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=729b21ae
[junit4] 2> 8218230 INFO (coreCloseExecutor-3692-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@29e21bdc: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@7224ae7b
[junit4] 2> 8218236 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 8218236 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@c466407: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@15733326
[junit4] 2> 8218242 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 8218242 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@46b67787: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1b5fba71
[junit4] 2> 8218244 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 8218244 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@37c07def: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@abd1a5c
[junit4] 2> 8218246 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6e619193{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 8218246 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@11ec1f67{/solr,null,UNAVAILABLE}
[junit4] 2> 8218246 INFO (TEST-TestReplicationHandlerBackup.doTestBackup-seed#[5BED2723F2025943]) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.handler.TestReplicationHandlerBackup_5BED2723F2025943-001
[junit4] 2> Jul 08, 2019 1:03:06 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: All leaked threads terminated.
[junit4] 2> NOTE: test params are: codec=Lucene80, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@504e08e9), locale=zh-TW, timezone=Etc/GMT-3
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 13-ea (64-bit)/cpus=3,threads=1,free=140754992,total=243875840
[junit4] 2> NOTE: All tests run in this JVM: [TestDistributedStatsComponentCardinality, DirectoryFactoryTest, TestDistributedMissingSort, TestConfigSetsAPIZkFailure, DeleteInactiveReplicaTest, TestCloudSearcherWarming, ClassificationUpdateProcessorTest, TestCoreDiscovery, TestCoreAdminApis, TestRangeQuery, TestBlobHandler, DistributedVersionInfoTest, AutoscalingHistoryHandlerTest, ReplaceNodeTest, TestStressCloudBlindAtomicUpdates, TestConfigSetsAPI, TestSafeXMLParsing, HdfsSyncSliceTest, QueryResultKeyTest, DistributedTermsComponentTest, TestReqParamsAPI, DistributedFacetPivotSmallTest, TestIndexSearcher, TestSolrDeletionPolicy1, TestDistribIDF, TestQueryTypes, TestLocalFSCloudBackupRestore, TestAddFieldRealTimeGet, ByteBuffersDirectoryFactoryTest, StatelessScriptUpdateProcessorFactoryTest, ComputePlanActionTest, BinaryUpdateRequestHandlerTest, TestSimpleTrackingShardHandler, TestSolrQueryResponse, SolrTestCaseJ4Test, GraphQueryTest, MergeStrategyTest, HLLUtilTest, TestDFISimilarityFactory, TestSystemCollAutoCreate, HdfsAutoAddReplicasIntegrationTest, HdfsWriteToMultipleCollectionsTest, SOLR749Test, TestConfig, TestSolrConfigHandlerCloud, TestLegacyFieldReuse, SolrCloudReportersTest, TestSortByMinMaxFunction, CleanupOldIndexTest, BlockJoinFacetRandomTest, CloudMLTQParserTest, PeerSyncReplicationTest, TermVectorComponentTest, ChaosMonkeySafeLeaderWithPullReplicasTest, TestManagedSchemaThreadSafety, ClassificationUpdateProcessorFactoryTest, TestAuthorizationFramework, TestDynamicLoading, TestHighFrequencyDictionaryFactory, UninvertDocValuesMergePolicyTest, TestJmxIntegration, TestLockTree, BooleanFieldTest, PeerSyncTest, SuggesterFSTTest, IndexSchemaTest, TestFieldTypeResource, AnalyticsQueryTest, SimplePostToolTest, TestMacros, HdfsCollectionsAPIDistributedZkTest, TestCursorMarkWithoutUniqueKey, SpellPossibilityIteratorTest, TestSubQueryTransformerCrossCore, FileUtilsTest, TestRebalanceLeaders, TestRealTimeGet, CoreAdminHandlerTest, TestDocumentBuilder, TestOverriddenPrefixQueryForCustomFieldType, SchemaApiFailureTest, UUIDUpdateProcessorFallbackTest, TestCustomStream, TestTolerantSearch, TestValueSourceCache, SolrMetricReporterTest, ConjunctionSolrSpellCheckerTest, TestDocSet, TestLuceneIndexBackCompat, TestUnifiedSolrHighlighter, DataDrivenBlockJoinTest, TestXIncludeConfig, HdfsBasicDistributedZk2Test, HdfsTlogReplayBufferedWhileIndexingTest, TestHttpShardHandlerFactory, TestReplicationHandlerBackup]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestReplicationHandlerBackup -Dtests.seed=5BED2723F2025943 -Dtests.slow=true -Dtests.locale=zh-TW -Dtests.timezone=Etc/GMT-3 -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J1 | TestReplicationHandlerBackup (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([5BED2723F2025943]:0)
[junit4] Completed [748/867 (1!)] on J1 in 7210.30s, 2 tests, 2 errors <<< FAILURES!
[...truncated 45234 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8041/consoleText
[repro] Revision: dd4813d5b82d7e983a3541be54cb9b0e04f246ce
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC"
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] TestReplicationHandlerBackup
[repro] ant compile-test
[...truncated 3355 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestReplicationHandlerBackup" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=5BED2723F2025943 -Dtests.slow=true -Dtests.locale=zh-TW -Dtests.timezone=Etc/GMT-3 -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[...truncated 78 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.handler.TestReplicationHandlerBackup
[repro] Exiting with code 0
[...truncated 78 lines...]
[JENKINS-EA] Lucene-Solr-master-Windows (64bit/jdk-13-ea+26) -
Build # 8042 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8042/
Java: 64bit/jdk-13-ea+26 -XX:-UseCompressedOops -XX:+UseG1GC
2 tests failed.
FAILED: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testClassifyStream
Error Message:
expected:<0.0> but was:<0.9998245650830389>
Stack Trace:
java.lang.AssertionError: expected:<0.0> but was:<0.9998245650830389>
at __randomizedtesting.SeedInfo.seed([BA8ADD4C6B18F5F6:1FC247745240EC62]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:553)
at org.junit.Assert.assertEquals(Assert.java:683)
at org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testClassifyStream(StreamDecoratorTest.java:3680)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:830)
FAILED: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testClassifyStream
Error Message:
expected:<0.0> but was:<0.9998245650830389>
Stack Trace:
java.lang.AssertionError: expected:<0.0> but was:<0.9998245650830389>
at __randomizedtesting.SeedInfo.seed([BA8ADD4C6B18F5F6:1FC247745240EC62]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:553)
at org.junit.Assert.assertEquals(Assert.java:683)
at org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testClassifyStream(StreamDecoratorTest.java:3680)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
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 org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:830)
Build Log:
[...truncated 16721 lines...]
[junit4] Suite: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest
[junit4] 2> 388890 INFO (SUITE-StreamDecoratorTest-seed#[BA8ADD4C6B18F5F6]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_BA8ADD4C6B18F5F6-001\init-core-data-001
[junit4] 2> 388890 INFO (SUITE-StreamDecoratorTest-seed#[BA8ADD4C6B18F5F6]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 388890 INFO (SUITE-StreamDecoratorTest-seed#[BA8ADD4C6B18F5F6]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="None")
[junit4] 2> 388890 INFO (SUITE-StreamDecoratorTest-seed#[BA8ADD4C6B18F5F6]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_BA8ADD4C6B18F5F6-001\tempDir-001
[junit4] 2> 388910 INFO (SUITE-StreamDecoratorTest-seed#[BA8ADD4C6B18F5F6]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 388910 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 388910 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 389034 INFO (SUITE-StreamDecoratorTest-seed#[BA8ADD4C6B18F5F6]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:57519
[junit4] 2> 389034 INFO (SUITE-StreamDecoratorTest-seed#[BA8ADD4C6B18F5F6]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:57519
[junit4] 2> 389034 INFO (SUITE-StreamDecoratorTest-seed#[BA8ADD4C6B18F5F6]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 57519
[junit4] 2> 389038 INFO (SUITE-StreamDecoratorTest-seed#[BA8ADD4C6B18F5F6]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 389070 INFO (zkConnectionManagerCallback-3617-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 389070 INFO (SUITE-StreamDecoratorTest-seed#[BA8ADD4C6B18F5F6]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 389086 INFO (SUITE-StreamDecoratorTest-seed#[BA8ADD4C6B18F5F6]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 389108 INFO (zkConnectionManagerCallback-3619-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 389108 INFO (SUITE-StreamDecoratorTest-seed#[BA8ADD4C6B18F5F6]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 389123 INFO (SUITE-StreamDecoratorTest-seed#[BA8ADD4C6B18F5F6]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 389156 INFO (zkConnectionManagerCallback-3621-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 389156 INFO (SUITE-StreamDecoratorTest-seed#[BA8ADD4C6B18F5F6]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 389313 WARN (jetty-launcher-3622-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 389313 INFO (jetty-launcher-3622-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 389313 INFO (jetty-launcher-3622-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 389313 INFO (jetty-launcher-3622-thread-1) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 13-ea+26
[junit4] 2> 389315 INFO (jetty-launcher-3622-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 389315 INFO (jetty-launcher-3622-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 389315 INFO (jetty-launcher-3622-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 389315 INFO (jetty-launcher-3622-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@321f7cde{/solr,null,AVAILABLE}
[junit4] 2> 389317 WARN (jetty-launcher-3622-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 389318 WARN (jetty-launcher-3622-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 389318 INFO (jetty-launcher-3622-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 389318 INFO (jetty-launcher-3622-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 389318 INFO (jetty-launcher-3622-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 389318 INFO (jetty-launcher-3622-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 389318 INFO (jetty-launcher-3622-thread-2) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 13-ea+26
[junit4] 2> 389318 INFO (jetty-launcher-3622-thread-3) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 13-ea+26
[junit4] 2> 389319 WARN (jetty-launcher-3622-thread-4) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 389319 INFO (jetty-launcher-3622-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 389319 INFO (jetty-launcher-3622-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 389319 INFO (jetty-launcher-3622-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 389320 INFO (jetty-launcher-3622-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@40e97282{/solr,null,AVAILABLE}
[junit4] 2> 389321 INFO (jetty-launcher-3622-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 389321 INFO (jetty-launcher-3622-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 389321 INFO (jetty-launcher-3622-thread-4) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 13-ea+26
[junit4] 2> 389321 INFO (jetty-launcher-3622-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 389321 INFO (jetty-launcher-3622-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 389321 INFO (jetty-launcher-3622-thread-4) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 389323 INFO (jetty-launcher-3622-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4ccd6339{/solr,null,AVAILABLE}
[junit4] 2> 389323 INFO (jetty-launcher-3622-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 389323 INFO (jetty-launcher-3622-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 389323 INFO (jetty-launcher-3622-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 389323 INFO (jetty-launcher-3622-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4dd807fa{/solr,null,AVAILABLE}
[junit4] 2> 389326 INFO (jetty-launcher-3622-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@444d8cd3{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:?????}
[junit4] 2> 389326 INFO (jetty-launcher-3622-thread-1) [ ] o.e.j.s.Server Started @??????ms
[junit4] 2> 389326 INFO (jetty-launcher-3622-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57535}
[junit4] 2> 389326 ERROR (jetty-launcher-3622-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 389326 INFO (jetty-launcher-3622-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 389326 INFO (jetty-launcher-3622-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 389326 INFO (jetty-launcher-3622-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 389326 INFO (jetty-launcher-3622-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 389326 INFO (jetty-launcher-3622-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-07-08T14:03:32.439825400Z
[junit4] 2> 389336 INFO (jetty-launcher-3622-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 389336 INFO (jetty-launcher-3622-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@33f96f0{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:?????}
[junit4] 2> 389336 INFO (jetty-launcher-3622-thread-2) [ ] o.e.j.s.Server Started @??????ms
[junit4] 2> 389336 INFO (jetty-launcher-3622-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57538}
[junit4] 2> 389336 ERROR (jetty-launcher-3622-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 389336 INFO (jetty-launcher-3622-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 389336 INFO (jetty-launcher-3622-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 389336 INFO (jetty-launcher-3622-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 389336 INFO (jetty-launcher-3622-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 389336 INFO (jetty-launcher-3622-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-07-08T14:03:32.449365500Z
[junit4] 2> 389336 INFO (jetty-launcher-3622-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5ab55872{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:?????}
[junit4] 2> 389336 INFO (jetty-launcher-3622-thread-4) [ ] o.e.j.s.Server Started @??????ms
[junit4] 2> 389336 INFO (jetty-launcher-3622-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57541}
[junit4] 2> 389336 ERROR (jetty-launcher-3622-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 389336 INFO (jetty-launcher-3622-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 389336 INFO (jetty-launcher-3622-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 389336 INFO (jetty-launcher-3622-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 389336 INFO (jetty-launcher-3622-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 389336 INFO (jetty-launcher-3622-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-07-08T14:03:32.449365500Z
[junit4] 2> 389336 INFO (jetty-launcher-3622-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 389342 INFO (jetty-launcher-3622-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 389355 INFO (jetty-launcher-3622-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@50286ece{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:?????}
[junit4] 2> 389355 INFO (jetty-launcher-3622-thread-3) [ ] o.e.j.s.Server Started @??????ms
[junit4] 2> 389355 INFO (jetty-launcher-3622-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=57543}
[junit4] 2> 389355 ERROR (jetty-launcher-3622-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 389355 INFO (jetty-launcher-3622-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 389355 INFO (jetty-launcher-3622-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 389355 INFO (jetty-launcher-3622-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 389355 INFO (jetty-launcher-3622-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 389355 INFO (jetty-launcher-3622-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-07-08T14:03:32.468402200Z
[junit4] 2> 389357 INFO (jetty-launcher-3622-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 389367 INFO (zkConnectionManagerCallback-3624-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 389367 INFO (jetty-launcher-3622-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 389367 INFO (zkConnectionManagerCallback-3626-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 389367 INFO (jetty-launcher-3622-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 389367 INFO (jetty-launcher-3622-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 389367 INFO (jetty-launcher-3622-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 389367 INFO (zkConnectionManagerCallback-3628-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 389367 INFO (jetty-launcher-3622-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 389397 INFO (zkConnectionManagerCallback-3630-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 389397 INFO (jetty-launcher-3622-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 389400 INFO (jetty-launcher-3622-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 389401 INFO (jetty-launcher-3622-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 389765 INFO (jetty-launcher-3622-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 389839 WARN (jetty-launcher-3622-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@51f28a40[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 389839 WARN (jetty-launcher-3622-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@51f28a40[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 390007 INFO (jetty-launcher-3622-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 390025 WARN (jetty-launcher-3622-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@47c54927[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 390025 WARN (jetty-launcher-3622-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@47c54927[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 390026 INFO (jetty-launcher-3622-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57519/solr
[junit4] 2> 390032 INFO (jetty-launcher-3622-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 390044 INFO (jetty-launcher-3622-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 390129 WARN (jetty-launcher-3622-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2d6dd103[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 390129 WARN (jetty-launcher-3622-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2d6dd103[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 390141 WARN (jetty-launcher-3622-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@300f436c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 390141 WARN (jetty-launcher-3622-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@300f436c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 390141 INFO (jetty-launcher-3622-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57519/solr
[junit4] 2> 390141 WARN (jetty-launcher-3622-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@213d8154[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 390141 WARN (jetty-launcher-3622-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@213d8154[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 390145 INFO (jetty-launcher-3622-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 390196 INFO (zkConnectionManagerCallback-3641-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 390196 INFO (jetty-launcher-3622-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 390213 WARN (jetty-launcher-3622-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@618b8e77[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 390213 WARN (jetty-launcher-3622-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@618b8e77[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 390213 INFO (jetty-launcher-3622-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57519/solr
[junit4] 2> 390216 INFO (zkConnectionManagerCallback-3647-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 390216 INFO (jetty-launcher-3622-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 390226 INFO (jetty-launcher-3622-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 390244 INFO (zkConnectionManagerCallback-3652-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 390245 INFO (jetty-launcher-3622-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 390333 INFO (jetty-launcher-3622-thread-1) [n:127.0.0.1:57535_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 390354 INFO (jetty-launcher-3622-thread-4) [n:127.0.0.1:57541_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 390360 INFO (zkConnectionManagerCallback-3654-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 390360 INFO (jetty-launcher-3622-thread-1) [n:127.0.0.1:57535_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 390371 INFO (zkConnectionManagerCallback-3656-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 390371 INFO (jetty-launcher-3622-thread-4) [n:127.0.0.1:57541_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 390421 WARN (jetty-launcher-3622-thread-1) [n:127.0.0.1:57535_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 390421 WARN (jetty-launcher-3622-thread-4) [n:127.0.0.1:57541_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 390435 INFO (jetty-launcher-3622-thread-4) [n:127.0.0.1:57541_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:57541_solr
[junit4] 2> 390440 INFO (jetty-launcher-3622-thread-4) [n:127.0.0.1:57541_solr ] o.a.s.c.Overseer Overseer (id=72093452578848779-127.0.0.1:57541_solr-n_0000000000) starting
[junit4] 2> 390441 INFO (jetty-launcher-3622-thread-1) [n:127.0.0.1:57535_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57535_solr
[junit4] 2> 390467 INFO (jetty-launcher-3622-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 390469 INFO (jetty-launcher-3622-thread-1) [n:127.0.0.1:57535_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 390470 INFO (zkCallback-3655-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 390471 INFO (zkCallback-3653-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 390472 INFO (jetty-launcher-3622-thread-4) [n:127.0.0.1:57541_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 390472 WARN (jetty-launcher-3622-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6793c35[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 390472 WARN (jetty-launcher-3622-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6793c35[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 390480 WARN (jetty-launcher-3622-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7fb8b1c4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 390480 WARN (jetty-launcher-3622-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7fb8b1c4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 390482 INFO (jetty-launcher-3622-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:57519/solr
[junit4] 2> 390484 INFO (jetty-launcher-3622-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 390484 INFO (jetty-launcher-3622-thread-3) [n:127.0.0.1:57543_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 390484 INFO (zkConnectionManagerCallback-3665-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 390484 INFO (jetty-launcher-3622-thread-1) [n:127.0.0.1:57535_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 390492 INFO (zkConnectionManagerCallback-3671-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 390492 INFO (jetty-launcher-3622-thread-4) [n:127.0.0.1:57541_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 390492 INFO (zkConnectionManagerCallback-3678-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 390492 INFO (jetty-launcher-3622-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 390501 INFO (jetty-launcher-3622-thread-1) [n:127.0.0.1:57535_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 390501 INFO (jetty-launcher-3622-thread-4) [n:127.0.0.1:57541_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 390501 INFO (zkConnectionManagerCallback-3676-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 390503 INFO (jetty-launcher-3622-thread-3) [n:127.0.0.1:57543_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 390504 INFO (jetty-launcher-3622-thread-1) [n:127.0.0.1:57535_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57519/solr ready
[junit4] 2> 390505 INFO (jetty-launcher-3622-thread-4) [n:127.0.0.1:57541_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57519/solr ready
[junit4] 2> 390506 INFO (jetty-launcher-3622-thread-4) [n:127.0.0.1:57541_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:57541_solr as DOWN
[junit4] 2> 390508 INFO (jetty-launcher-3622-thread-4) [n:127.0.0.1:57541_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 390508 INFO (jetty-launcher-3622-thread-4) [n:127.0.0.1:57541_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57541_solr
[junit4] 2> 390524 INFO (zkCallback-3653-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 390524 INFO (OverseerStateUpdate-72093452578848779-127.0.0.1:57541_solr-n_0000000000) [n:127.0.0.1:57541_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:57541_solr
[junit4] 2> 390524 INFO (zkCallback-3655-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 390540 WARN (jetty-launcher-3622-thread-3) [n:127.0.0.1:57543_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 390558 INFO (jetty-launcher-3622-thread-3) [n:127.0.0.1:57543_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 390605 INFO (zkCallback-3670-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 390605 INFO (zkCallback-3664-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 390627 INFO (jetty-launcher-3622-thread-2) [n:127.0.0.1:57538_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 390627 INFO (jetty-launcher-3622-thread-1) [n:127.0.0.1:57535_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 390640 INFO (jetty-launcher-3622-thread-3) [n:127.0.0.1:57543_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:57543_solr as DOWN
[junit4] 2> 390648 INFO (jetty-launcher-3622-thread-3) [n:127.0.0.1:57543_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 390648 INFO (jetty-launcher-3622-thread-3) [n:127.0.0.1:57543_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57543_solr
[junit4] 2> 390656 INFO (zkCallback-3664-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 390657 INFO (jetty-launcher-3622-thread-4) [n:127.0.0.1:57541_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 390657 INFO (zkCallback-3670-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 390657 INFO (zkCallback-3655-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 390657 INFO (zkCallback-3653-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 390672 INFO (jetty-launcher-3622-thread-3) [n:127.0.0.1:57543_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 390704 INFO (jetty-launcher-3622-thread-1) [n:127.0.0.1:57535_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57535.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25032a0d
[junit4] 2> 390732 INFO (zkCallback-3675-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 390744 INFO (jetty-launcher-3622-thread-1) [n:127.0.0.1:57535_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57535.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25032a0d
[junit4] 2> 390744 INFO (jetty-launcher-3622-thread-1) [n:127.0.0.1:57535_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57535.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25032a0d
[junit4] 2> 390744 INFO (zkConnectionManagerCallback-3682-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 390744 INFO (jetty-launcher-3622-thread-2) [n:127.0.0.1:57538_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 390752 INFO (jetty-launcher-3622-thread-1) [n:127.0.0.1:57535_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_BA8ADD4C6B18F5F6-001\tempDir-001\node1\.
[junit4] 2> 390752 INFO (zkConnectionManagerCallback-3689-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 390752 INFO (jetty-launcher-3622-thread-3) [n:127.0.0.1:57543_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 390779 INFO (jetty-launcher-3622-thread-4) [n:127.0.0.1:57541_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57541.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25032a0d
[junit4] 2> 390801 INFO (jetty-launcher-3622-thread-4) [n:127.0.0.1:57541_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57541.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25032a0d
[junit4] 2> 390802 INFO (jetty-launcher-3622-thread-4) [n:127.0.0.1:57541_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57541.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25032a0d
[junit4] 2> 390803 INFO (jetty-launcher-3622-thread-4) [n:127.0.0.1:57541_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_BA8ADD4C6B18F5F6-001\tempDir-001\node4\.
[junit4] 2> 390823 INFO (jetty-launcher-3622-thread-3) [n:127.0.0.1:57543_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 390826 INFO (jetty-launcher-3622-thread-3) [n:127.0.0.1:57543_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57519/solr ready
[junit4] 2> 390841 WARN (jetty-launcher-3622-thread-2) [n:127.0.0.1:57538_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 390844 INFO (jetty-launcher-3622-thread-2) [n:127.0.0.1:57538_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 390856 INFO (jetty-launcher-3622-thread-2) [n:127.0.0.1:57538_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:57538_solr as DOWN
[junit4] 2> 390864 INFO (jetty-launcher-3622-thread-2) [n:127.0.0.1:57538_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 390864 INFO (jetty-launcher-3622-thread-2) [n:127.0.0.1:57538_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:57538_solr
[junit4] 2> 390882 INFO (zkCallback-3653-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 390882 INFO (zkCallback-3664-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 390882 INFO (zkCallback-3655-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 390882 INFO (zkCallback-3675-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 390882 INFO (zkCallback-3670-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 390889 INFO (jetty-launcher-3622-thread-2) [n:127.0.0.1:57538_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 390891 INFO (zkCallback-3688-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 390891 INFO (zkCallback-3681-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 390910 INFO (jetty-launcher-3622-thread-3) [n:127.0.0.1:57543_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 390932 INFO (zkConnectionManagerCallback-3696-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 390932 INFO (jetty-launcher-3622-thread-2) [n:127.0.0.1:57538_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 390946 INFO (jetty-launcher-3622-thread-2) [n:127.0.0.1:57538_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 390948 INFO (jetty-launcher-3622-thread-2) [n:127.0.0.1:57538_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57519/solr ready
[junit4] 2> 390959 INFO (jetty-launcher-3622-thread-3) [n:127.0.0.1:57543_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57543.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25032a0d
[junit4] 2> 390973 INFO (jetty-launcher-3622-thread-3) [n:127.0.0.1:57543_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57543.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25032a0d
[junit4] 2> 390973 INFO (jetty-launcher-3622-thread-3) [n:127.0.0.1:57543_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57543.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25032a0d
[junit4] 2> 390981 INFO (jetty-launcher-3622-thread-3) [n:127.0.0.1:57543_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_BA8ADD4C6B18F5F6-001\tempDir-001\node3\.
[junit4] 2> 391033 INFO (jetty-launcher-3622-thread-2) [n:127.0.0.1:57538_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 391080 INFO (jetty-launcher-3622-thread-2) [n:127.0.0.1:57538_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57538.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25032a0d
[junit4] 2> 391094 INFO (jetty-launcher-3622-thread-2) [n:127.0.0.1:57538_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57538.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25032a0d
[junit4] 2> 391095 INFO (jetty-launcher-3622-thread-2) [n:127.0.0.1:57538_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57538.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25032a0d
[junit4] 2> 391096 INFO (jetty-launcher-3622-thread-2) [n:127.0.0.1:57538_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_BA8ADD4C6B18F5F6-001\tempDir-001\node2\.
[junit4] 2> 391256 INFO (SUITE-StreamDecoratorTest-seed#[BA8ADD4C6B18F5F6]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 391263 INFO (zkConnectionManagerCallback-3703-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 391263 INFO (SUITE-StreamDecoratorTest-seed#[BA8ADD4C6B18F5F6]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 391263 INFO (SUITE-StreamDecoratorTest-seed#[BA8ADD4C6B18F5F6]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 391269 INFO (SUITE-StreamDecoratorTest-seed#[BA8ADD4C6B18F5F6]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57519/solr ready
[junit4] 2> 391327 INFO (qtp1250589682-7355) [n:127.0.0.1:57535_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 391350 INFO (OverseerThreadFactory-1236-thread-1-processing-n:127.0.0.1:57541_solr) [n:127.0.0.1:57541_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 391564 INFO (OverseerStateUpdate-72093452578848779-127.0.0.1:57541_solr-n_0000000000) [n:127.0.0.1:57541_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:57541/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 391604 INFO (OverseerStateUpdate-72093452578848779-127.0.0.1:57541_solr-n_0000000000) [n:127.0.0.1:57541_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:57543/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 391863 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr x:collection1_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 391883 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr x:collection1_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 393072 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 393107 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=test
[junit4] 2> 393169 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 393214 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema [collection1_shard2_replica_n2] Schema name=test
[junit4] 2> 393293 WARN (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 393294 WARN (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 393295 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 393295 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using configuration from collection collection1, trusted=true
[junit4] 2> 393295 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57541.solr.core.collection1.shard1.replica_n1' (registry 'solr.core.collection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25032a0d
[junit4] 2> 393296 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_BA8ADD4C6B18F5F6-001\tempDir-001\node4\collection1_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_BA8ADD4C6B18F5F6-001\tempDir-001\node4\.\collection1_shard1_replica_n1\data\]
[junit4] 2> 393335 WARN (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 393335 WARN (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 393335 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 393335 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n2' using configuration from collection collection1, trusted=true
[junit4] 2> 393338 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_57543.solr.core.collection1.shard2.replica_n2' (registry 'solr.core.collection1.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25032a0d
[junit4] 2> 393338 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrCore [[collection1_shard2_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_BA8ADD4C6B18F5F6-001\tempDir-001\node3\collection1_shard2_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_BA8ADD4C6B18F5F6-001\tempDir-001\node3\.\collection1_shard2_replica_n2\data\]
[junit4] 2> 393576 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 393576 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 393584 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 393584 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 393589 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@55774f89[collection1_shard1_replica_n1] main]
[junit4] 2> 393615 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 393616 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 393616 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 393620 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 393620 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 393620 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1638499212835422208
[junit4] 2> 393620 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 393620 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 393634 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4559656e[collection1_shard2_replica_n2] main]
[junit4] 2> 393635 INFO (searcherExecutor-1253-thread-1-processing-n:127.0.0.1:57541_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node3) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher Searcher@55774f89[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 393670 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 393671 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 393671 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 393671 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1638499212888899584
[junit4] 2> 393693 INFO (searcherExecutor-1254-thread-1-processing-n:127.0.0.1:57543_solr x:collection1_shard2_replica_n2 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrCore [collection1_shard2_replica_n2] Registered new searcher Searcher@4559656e[collection1_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 393694 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 393694 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1
[junit4] 2> 393719 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 393719 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard2
[junit4] 2> 393729 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 393729 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 393729 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:57541/solr/collection1_shard1_replica_n1/
[junit4] 2> 393730 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 393731 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:57541/solr/collection1_shard1_replica_n1/ has no replicas
[junit4] 2> 393731 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/72093452578848779-core_node3-n_0000000000
[junit4] 2> 393736 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 393736 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 393736 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:57541/solr/collection1_shard1_replica_n1/ shard1
[junit4] 2> 393736 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:57543/solr/collection1_shard2_replica_n2/
[junit4] 2> 393737 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 393738 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SyncStrategy http://127.0.0.1:57543/solr/collection1_shard2_replica_n2/ has no replicas
[junit4] 2> 393738 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard2/leader after winning as /collections/collection1/leader_elect/shard2/election/72093452578848783-core_node4-n_0000000000
[junit4] 2> 393746 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:57543/solr/collection1_shard2_replica_n2/ shard2
[junit4] 2> 393881 INFO (zkCallback-3675-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 393881 INFO (zkCallback-3655-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 393882 INFO (zkCallback-3675-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 393883 INFO (zkCallback-3655-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 393888 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 393888 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 393915 INFO (qtp1592757091-7383) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1840
[junit4] 2> 393915 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1855
[junit4] 2> 393955 INFO (qtp1250589682-7355) [n:127.0.0.1:57535_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 394040 INFO (zkCallback-3653-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 394040 INFO (zkCallback-3675-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 394040 INFO (zkCallback-3675-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 394040 INFO (zkCallback-3655-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 394041 INFO (zkCallback-3655-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 394041 INFO (zkCallback-3655-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 394042 INFO (qtp1250589682-7355) [n:127.0.0.1:57535_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=2457
[junit4] 2> 394042 INFO (zkCallback-3675-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 394107 INFO (SUITE-StreamDecoratorTest-seed#[BA8ADD4C6B18F5F6]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):45
[junit4] 2> 394138 INFO (SUITE-StreamDecoratorTest-seed#[BA8ADD4C6B18F5F6]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
[junit4] 2> 394364 INFO (TEST-StreamDecoratorTest.testPriorityStream-seed#[BA8ADD4C6B18F5F6]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testPriorityStream
[junit4] 2> 394484 INFO (qtp1592757091-7387) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard2 to Terms{values={core_node4=1}, version=1}
[junit4] 2> 394484 INFO (qtp1592757091-7387) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:57541/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1638499213686865920)} 0 65
[junit4] 2> 394488 INFO (qtp1592757091-7388) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1638499213745586176,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 394488 INFO (qtp1592757091-7388) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 394491 INFO (qtp1592757091-7388) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 394493 INFO (qtp1592757091-7388) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:57541/solr/collection1_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 6
[junit4] 2> 394494 INFO (qtp1416801420-7378) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1638499213751877632,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 394494 INFO (qtp1416801420-7378) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 394496 INFO (qtp1416801420-7378) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 394521 INFO (qtp1416801420-7378) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard1 to Terms{values={core_node3=1}, version=1}
[junit4] 2> 394521 INFO (qtp1416801420-7378) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1638499213643874304),commit=} 0 118
[junit4] 2> 394535 INFO (qtp1592757091-7400) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=collection1:4&wt=javabin&version=2}{add=[2 (1638499213790674944), 3 (1638499213794869248), 5 (1638499213794869249), 6 (1638499213794869250), 7 (1638499213794869251), 9 (1638499213794869252)]} 0 3
[junit4] 2> 394537 INFO (qtp1416801420-7379) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:4&wt=javabin&version=2}{add=[0 (1638499213790674944), 4 (1638499213794869248), 1 (1638499213794869249), 8 (1638499213795917824)]} 0 12
[junit4] 2> 394542 INFO (qtp1416801420-7395) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1638499213802209280,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 394547 INFO (qtp1416801420-7395) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@70d969 commitCommandVersion:1638499213802209280
[junit4] 2> 394658 INFO (qtp1416801420-7395) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5556c7fe[collection1_shard1_replica_n1] main]
[junit4] 2> 394661 INFO (qtp1416801420-7395) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 394664 INFO (searcherExecutor-1253-thread-1-processing-n:127.0.0.1:57541_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node3) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher Searcher@5556c7fe[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(_0(9.0.0):c4:[diagnostics={java.runtime.version=13-ea+26, os.version=10.0, os=Windows 10, java.vendor=Oracle Corporation, timestamp=1562594617731, java.version=13-ea, java.vm.version=13-ea+26, lucene.version=9.0.0, source=flush, os.arch=amd64}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]))}
[junit4] 2> 394664 INFO (qtp1416801420-7395) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:57543/solr/collection1_shard2_replica_n2/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 107
[junit4] 2> 394670 INFO (qtp1592757091-7384) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1638499213936427008,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 394702 INFO (qtp1592757091-7384) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1d44cc27 commitCommandVersion:1638499213936427008
[junit4] 2> 394776 INFO (qtp1592757091-7384) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1fcc605d[collection1_shard2_replica_n2] main]
[junit4] 2> 394776 INFO (qtp1592757091-7384) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 394776 INFO (searcherExecutor-1254-thread-1-processing-n:127.0.0.1:57543_solr x:collection1_shard2_replica_n2 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrCore [collection1_shard2_replica_n2] Registered new searcher Searcher@1fcc605d[collection1_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader(_0(9.0.0):c6:[diagnostics={java.runtime.version=13-ea+26, os.version=10.0, os=Windows 10, java.vendor=Oracle Corporation, timestamp=1562594617861, java.version=13-ea, java.vm.version=13-ea+26, lucene.version=9.0.0, source=flush, os.arch=amd64}]:[attributes={Lucene50StoredFieldsFormat.mode=BEST_SPEED}]))}
[junit4] 2> 394786 INFO (qtp1592757091-7384) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2}{commit=} 0 225
[junit4] 2> 394793 INFO (TEST-StreamDecoratorTest.testPriorityStream-seed#[BA8ADD4C6B18F5F6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 394829 INFO (zkConnectionManagerCallback-3710-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 394829 INFO (TEST-StreamDecoratorTest.testPriorityStream-seed#[BA8ADD4C6B18F5F6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 394836 INFO (TEST-StreamDecoratorTest.testPriorityStream-seed#[BA8ADD4C6B18F5F6]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 394837 INFO (TEST-StreamDecoratorTest.testPriorityStream-seed#[BA8ADD4C6B18F5F6]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:57519/solr ready
[junit4] 2> 394913 INFO (qtp1592757091-7385) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard2=http://127.0.0.1:57543/solr/collection1_shard2_replica_n2/
[junit4] 2> 394913 INFO (qtp1592757091-7385) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=http://127.0.0.1:57541/solr/collection1_shard1_replica_n1/
[junit4] 2> 395007 INFO (qtp1416801420-7377) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&omitHeader=false&shards.purpose=1&NOW=1562594618025&ids=1000000&isShard=true&shard.url=http://127.0.0.1:57541/solr/collection1_shard1_replica_n1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0
[junit4] 2> 395008 INFO (qtp1592757091-7385) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/get params={qt=/get&ids=1000000&wt=javabin&version=2} status=0 QTime=86
[junit4] 2> 395019 INFO (qtp1592757091-7386) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/select params={q=a_s:hello&distrib=false&fl=id,a_i,_version_&initialCheckpoint=0&sort=_version_+asc&rows=500&wt=json&version=2.2} hits=3 status=0 QTime=1
[junit4] 2> 395025 INFO (qtp1416801420-7379) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/select params={q=a_s:hello&distrib=false&fl=id,a_i,_version_&initialCheckpoint=0&sort=_version_+asc&rows=500&wt=json&version=2.2} hits=1 status=0 QTime=0
[junit4] 2> 395077 INFO (qtp1416801420-7390) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:4&collection=collection1&wt=javabin&version=2}{add=[1000000 (1638499214321254400)]} 0 36
[junit4] 2> 395102 INFO (qtp1592757091-7400) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard2=http://127.0.0.1:57543/solr/collection1_shard2_replica_n2/
[junit4] 2> 395102 INFO (qtp1592757091-7400) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=http://127.0.0.1:57541/solr/collection1_shard1_replica_n1/
[junit4] 2> 395109 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&omitHeader=false&shards.purpose=1&NOW=1562594618215&ids=1000000&isShard=true&shard.url=http://127.0.0.1:57541/solr/collection1_shard1_replica_n1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=1
[junit4] 2> 395113 INFO (qtp1592757091-7400) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/get params={qt=/get&ids=1000000&wt=javabin&version=2} status=0 QTime=13
[junit4] 2> 395128 INFO (qtp1592757091-7384) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/select params={q=a_s:hello&distrib=false&fl=id,a_i,_version_&initialCheckpoint=0&sort=_version_+asc&fq={!frange+cost%3D100+incl%3Dfalse+l%3D1638499213794869251}_version_&rows=500&wt=json&version=2.2} hits=0 status=0 QTime=4
[junit4] 2> 395128 INFO (qtp1416801420-7378) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/select params={q=a_s:hello&distrib=false&fl=id,a_i,_version_&initialCheckpoint=0&sort=_version_+asc&fq={!frange+cost%3D100+incl%3Dfalse+l%3D1638499213795917824}_version_&rows=500&wt=json&version=2.2} hits=0 status=0 QTime=0
[junit4] 2> 395138 INFO (qtp1416801420-7379) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:4&collection=collection1&wt=javabin&version=2}{add=[1000000 (1638499214427160576)]} 0 0
[junit4] 2> 395143 INFO (qtp1592757091-7386) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard2=http://127.0.0.1:57543/solr/collection1_shard2_replica_n2/
[junit4] 2> 395143 INFO (qtp1592757091-7386) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=http://127.0.0.1:57541/solr/collection1_shard1_replica_n1/
[junit4] 2> 395143 INFO (qtp1416801420-7395) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&omitHeader=false&shards.purpose=1&NOW=1562594618255&ids=2000000&isShard=true&shard.url=http://127.0.0.1:57541/solr/collection1_shard1_replica_n1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0
[junit4] 2> 395145 INFO (qtp1592757091-7386) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/get params={qt=/get&ids=2000000&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 395149 INFO (qtp1592757091-7388) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/select params={q=a_s:hello1&distrib=false&fl=id,a_i,_version_&initialCheckpoint=0&sort=_version_+asc&rows=500&wt=json&version=2.2} hits=3 status=0 QTime=0
[junit4] 2> 395152 INFO (qtp1416801420-7376) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/select params={q=a_s:hello1&distrib=false&fl=id,a_i,_version_&initialCheckpoint=0&sort=_version_+asc&rows=500&wt=json&version=2.2} hits=3 status=0 QTime=1
[junit4] 2> 395169 INFO (qtp1416801420-7377) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:4&collection=collection1&wt=javabin&version=2}{add=[2000000 (1638499214459666432)]} 0 0
[junit4] 2> 395173 INFO (qtp1592757091-7384) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard2=http://127.0.0.1:57543/solr/collection1_shard2_replica_n2/
[junit4] 2> 395173 INFO (qtp1592757091-7384) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=http://127.0.0.1:57541/solr/collection1_shard1_replica_n1/
[junit4] 2> 395176 INFO (qtp1416801420-7380) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/get params={distrib=false&qt=/get&omitHeader=false&shards.purpose=1&NOW=1562594618286&ids=1000000&isShard=true&shard.url=http://127.0.0.1:57541/solr/collection1_shard1_replica_n1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0
[junit4] 2> 395176 INFO (qtp1592757091-7384) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/get params={qt=/get&ids=1000000&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 395183 INFO (qtp1592757091-7385) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/select params={q=a_s:hello&distrib=false&fl=id,a_i,_version_&initialCheckpoint=0&sort=_version_+asc&fq={!frange+cost%3D100+incl%3Dfalse+l%3D1638499213794869251}_version_&rows=500&wt=json&version=2.2} hits=0 status=0 QTime=1
[junit4] 2> 395190 INFO (qtp1416801420-7390) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/select params={q=a_s:hello&distrib=false&fl=id,a_i,_version_&initialCheckpoint=0&sort=_version_+asc&fq={!frange+cost%3D100+incl%3Dfalse+l%3D1638499213795917824}_version_&rows=500&wt=json&version=2.2} hits=0 status=0 QTime=2
[junit4] 2> 395208 INFO (qtp1416801420-7375) [n:127.0.0.1:57541_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:4&collection=collection1&wt=javabin&version=2}{add=[1000000 (1638499214500560896)]} 0 0
[junit4] 2> 395229 INFO (qtp1592757091-7391) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard2=http://127.0.0.1:57543/solr/collection1_shard2_replica_n2/
[junit4] 2> 395229 INFO (qtp1592757091-7391) [n:127.0.0.1:57543_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=http://127.0.0.1:57541/solr/collection1_shard
[...truncated too long message...]
ionprops.json
[junit4] 2> 4 /solr/collections/parallelDestinationCollection/collectionprops.json
[junit4] 2> 4 /solr/configs/ml
[junit4] 2> 4 /solr/collections/destination/terms/shard2
[junit4] 2> 4 /solr/collections/mainCorpus/terms/shard2
[junit4] 2> 4 /solr/security.json
[junit4] 2> 4 /solr/collections/mainCorpus/terms/shard1
[junit4] 2> 3 /solr/collections/workQueue1/terms/shard2
[junit4] 2> 3 /solr/collections/destination1/terms/shard2
[junit4] 2> 3 /solr/collections/mainCorpus1/terms/shard1
[junit4] 2> 3 /solr/collections/modelCollection/terms/shard2
[junit4] 2> 3 /solr/collections/modelCollection/terms/shard1
[junit4] 2> 3 /solr/collections/destination/terms/shard1
[junit4] 2> 3 /solr/collections/checkpointCollection/terms/shard1
[junit4] 2> 3 /solr/collections/collection1/terms/shard1
[junit4] 2> 3 /solr/collections/collection1/terms/shard2
[junit4] 2> 3 /solr/collections/uknownCollection/terms/shard2
[junit4] 2> 3 /solr/collections/uknownCollection/terms/shard1
[junit4] 2> 3 /solr/collections/workQueue/terms/shard2
[junit4] 2> 3 /solr/collections/workQueue/terms/shard1
[junit4] 2> 2 /solr/collections/collection1/collectionprops.json
[junit4] 2> 2 /solr/collections/mainCorpus1/collectionprops.json
[junit4] 2> 2 /solr/collections/destinationCollection/collectionprops.json
[junit4] 2> 2 /solr/collections/workQueue/collectionprops.json
[junit4] 2> 2 /solr/collections/destination/collectionprops.json
[junit4] 2> 2 /solr/collections/destination1/collectionprops.json
[junit4] 2> 2 /solr/collections/workQueue1/collectionprops.json
[junit4] 2> 2 /solr/collections/checkpointCollection/terms/shard2
[junit4] 2> 2 /solr/collections/modelCollection/collectionprops.json
[junit4] 2> 2 /solr/collections/uknownCollection/collectionprops.json
[junit4] 2> 2 /solr/collections/mainCorpus/collectionprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 75 /solr/collections/parallelDestinationCollection1/state.json
[junit4] 2> 73 /solr/clusterstate.json
[junit4] 2> 41 /solr/collections/parallelDestinationCollection/state.json
[junit4] 2> 38 /solr/collections/destinationCollection/state.json
[junit4] 2> 27 /solr/collections/uknownCollection/state.json
[junit4] 2> 27 /solr/collections/modelCollection/state.json
[junit4] 2> 25 /solr/collections/collection1/state.json
[junit4] 2> 25 /solr/collections/checkpointCollection/state.json
[junit4] 2> 23 /solr/collections/workQueue/state.json
[junit4] 2> 20 /solr/collections/mainCorpus1/state.json
[junit4] 2> 20 /solr/collections/destination1/state.json
[junit4] 2> 19 /solr/collections/destination/state.json
[junit4] 2> 19 /solr/collections/workQueue1/state.json
[junit4] 2> 19 /solr/collections/mainCorpus/state.json
[junit4] 2> 2 /solr/overseer_elect/election/72093561666928649-127.0.0.1:56888_solr-n_0000000001
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 363 /solr/collections
[junit4] 2> 253 /solr/overseer/queue
[junit4] 2> 99 /solr/live_nodes
[junit4] 2> 94 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 368338 INFO (SUITE-StreamDecoratorTest-seed#[BA8ADD4C6B18F5F6]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:56874
[junit4] 2> 368338 INFO (SUITE-StreamDecoratorTest-seed#[BA8ADD4C6B18F5F6]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 56874
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J1\temp\solr.client.solrj.io.stream.StreamDecoratorTest_BA8ADD4C6B18F5F6-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {expr_s=FST50, name_s=PostingsFormat(name=LuceneVarGapFixedInterval), terms_ss=PostingsFormat(name=MockRandom), join2_s=PostingsFormat(name=MockRandom), field_s=PostingsFormat(name=Direct), multiDefault=PostingsFormat(name=MockRandom), subject=PostingsFormat(name=MockRandom), a1_s=PostingsFormat(name=MockRandom), a2_s=PostingsFormat(name=LuceneVarGapFixedInterval), body_t=PostingsFormat(name=LuceneVarGapFixedInterval), s_multi=PostingsFormat(name=LuceneVarGapFixedInterval), a_ss=PostingsFormat(name=Direct), a_s=PostingsFormat(name=LuceneVarGapFixedInterval), tv_text=PostingsFormat(name=Direct), text_s=FST50, id=FST50, text=PostingsFormat(name=LuceneVarGapFixedInterval), bbid_s=PostingsFormat(name=LuceneVarGapFixedInterval), type_s=PostingsFormat(name=Direct), checkpoint_ss=PostingsFormat(name=LuceneVarGapFixedInterval), test_t=PostingsFormat(name=LuceneVarGapFixedInterval), ident_s=PostingsFormat(name=MockRandom), extra_s=PostingsFormat(name=MockRandom), ykey_s=PostingsFormat(name=Direct), side_s=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{expr_s=DocValuesFormat(name=Lucene80), name_s=DocValuesFormat(name=Lucene80), iteration_i=DocValuesFormat(name=Lucene80), terms_ss=DocValuesFormat(name=Direct), join2_s=DocValuesFormat(name=Direct), field_s=DocValuesFormat(name=Asserting), multiDefault=DocValuesFormat(name=Direct), a1_s=DocValuesFormat(name=Direct), a_f=DocValuesFormat(name=Direct), intDefault=DocValuesFormat(name=Asserting), join1_i=DocValuesFormat(name=Lucene80), a2_s=DocValuesFormat(name=Lucene80), falseNegative_i=DocValuesFormat(name=Asserting), a_i=DocValuesFormat(name=Lucene80), join3_i=DocValuesFormat(name=Lucene80), test_i=DocValuesFormat(name=Direct), falsePositive_i=DocValuesFormat(name=Asserting), field_i=DocValuesFormat(name=Direct), s_multi=DocValuesFormat(name=Lucene80), a_ss=DocValuesFormat(name=Asserting), a_s=DocValuesFormat(name=Lucene80), text_s=DocValuesFormat(name=Lucene80), id=DocValuesFormat(name=Lucene80), bbid_s=DocValuesFormat(name=Lucene80), timestamp=DocValuesFormat(name=Asserting), idfs_ds=DocValuesFormat(name=Asserting), type_s=DocValuesFormat(name=Asserting), alpha_d=DocValuesFormat(name=Lucene80), i_multi=DocValuesFormat(name=Lucene80), weights_ds=DocValuesFormat(name=Lucene80), trueNegative_i=DocValuesFormat(name=Lucene80), b_ls=DocValuesFormat(name=Direct), out_i=DocValuesFormat(name=Direct), checkpoint_ss=DocValuesFormat(name=Lucene80), ident_s=DocValuesFormat(name=Direct), _version_=DocValuesFormat(name=Asserting), error_d=DocValuesFormat(name=Lucene80), extra_s=DocValuesFormat(name=Direct), ykey_s=DocValuesFormat(name=Asserting), side_s=DocValuesFormat(name=Lucene80), truePositive_i=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=856, maxMBSortInHeap=7.746844578896223, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4bfbac58), locale=ar-LB, timezone=Africa/El_Aaiun
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 13-ea (64-bit)/cpus=3,threads=1,free=271211904,total=481296384
[junit4] 2> NOTE: All tests run in this JVM: [StreamDecoratorTest, StreamDecoratorTest, StreamDecoratorTest]
[junit4] Completed [5/5 (1!)] on J1 in 90.05s, 49 tests, 1 failure <<< FAILURES!
[...truncated 10 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1492: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1019: There were test failures: 5 suites, 245 tests, 1 failure [seed: BA8ADD4C6B18F5F6]
Total time: 6 minutes 14 seconds
[repro] Setting last failure code to 1
[repro] Failures:
[repro] 1/5 failed: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest
[repro] Exiting with code 1
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST lucene\build move /y lucene\build lucene\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST solr\build move /y solr\build solr\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST lucene\build.orig move /y lucene\build.orig lucene\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST solr\build.orig move /y solr\build.orig solr\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>exit 0
Archiving artifacts
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=ac209b637d68c84ce1402b6b8967514ce9cf6854, workspace=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows
[WARNINGS] Computing warning deltas based on reference build #8041
Recording test results
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2