You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/10/31 11:51:41 UTC
[JENKINS] Lucene-Solr-8.x-Linux (64bit/jdk-13.0.2) - Build # 4854 -
Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/4854/
Java: 64bit/jdk-13.0.2 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.TestDistributedMissingSort.test
Error Message:
Error from server at https://127.0.0.1:35143/kdby/i/collection1: org.apache.solr.client.solrj.SolrServerException: java.lang.ArithmeticException: / by zero
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:35143/kdby/i/collection1: org.apache.solr.client.solrj.SolrServerException: java.lang.ArithmeticException: / by zero
at __randomizedtesting.SeedInfo.seed([74AC1D7E2A30F763:FCF822A484CC9A9B]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:681)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:214)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1003)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1018)
at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:625)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:677)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:655)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:634)
at org.apache.solr.TestDistributedMissingSort.testSortMissingLast(TestDistributedMissingSort.java:175)
at org.apache.solr.TestDistributedMissingSort.test(TestDistributedMissingSort.java:43)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1117)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
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.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
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 14867 lines...]
[junit4] Suite: org.apache.solr.TestDistributedMissingSort
[junit4] 2> 427487 INFO (SUITE-TestDistributedMissingSort-seed#[74AC1D7E2A30F763]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/server/solr/configsets/_default/conf'
[junit4] 2> 427487 INFO (SUITE-TestDistributedMissingSort-seed#[74AC1D7E2A30F763]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedMissingSort_74AC1D7E2A30F763-001/data-dir-79-001
[junit4] 2> 427487 WARN (SUITE-TestDistributedMissingSort-seed#[74AC1D7E2A30F763]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 427487 INFO (SUITE-TestDistributedMissingSort-seed#[74AC1D7E2A30F763]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 427488 INFO (SUITE-TestDistributedMissingSort-seed#[74AC1D7E2A30F763]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 427488 INFO (SUITE-TestDistributedMissingSort-seed#[74AC1D7E2A30F763]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 427488 INFO (SUITE-TestDistributedMissingSort-seed#[74AC1D7E2A30F763]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /kdby/i
[junit4] 2> 427582 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedMissingSort_74AC1D7E2A30F763-001/tempDir-001/control/cores/collection1
[junit4] 2> 427584 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 427584 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 427584 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.e.j.s.Server jetty-9.4.32.v20200930; built: 2020-09-30T16:16:37.804Z; git: de97d26f7bd222a0e16831e353d702a7a422f711; jvm 13.0.2+8
[junit4] 2> 427585 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 427585 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 427585 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 427585 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@16da4baa{/kdby/i,null,AVAILABLE}
[junit4] 2> 427586 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@36d135d9{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:45623}
[junit4] 2> 427586 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.e.j.s.Server Started @427611ms
[junit4] 2> 427586 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-distributed-missing-sort.xml, hostContext=/kdby/i, hostPort=45623, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedMissingSort_74AC1D7E2A30F763-001/tempDir-001/control/cores}
[junit4] 2> 427586 ERROR (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 427586 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 427586 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.8.0
[junit4] 2> 427586 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 427586 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 427586 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-31T11:32:32.515665Z
[junit4] 2> 427586 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedMissingSort_74AC1D7E2A30F763-001/tempDir-001/control/solr.xml
[junit4] 2> 427588 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 427588 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 427589 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@432ca8d6, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 427612 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 427613 WARN (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4a4738c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 427613 WARN (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4a4738c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 427614 WARN (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4b8c5d30[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 427614 WARN (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4b8c5d30[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 427615 WARN (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 427621 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 427621 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 427631 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@432ca8d6
[junit4] 2> 427637 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@432ca8d6
[junit4] 2> 427637 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@432ca8d6
[junit4] 2> 427638 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedMissingSort_74AC1D7E2A30F763-001/tempDir-001/control/cores
[junit4] 2> 427638 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 427642 INFO (coreLoadExecutor-5710-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0
[junit4] 2> 427658 INFO (coreLoadExecutor-5710-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test-distributed-missing-sort
[junit4] 2> 427662 INFO (coreLoadExecutor-5710-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-distributed-missing-sort/1.6 with uniqueid field id
[junit4] 2> 427662 INFO (coreLoadExecutor-5710-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 427663 INFO (coreLoadExecutor-5710-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@432ca8d6
[junit4] 2> 427663 INFO (coreLoadExecutor-5710-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedMissingSort_74AC1D7E2A30F763-001/tempDir-001/control/cores/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedMissingSort_74AC1D7E2A30F763-001/tempDir-001/control/cores/collection1/data/]
[junit4] 2> 427663 INFO (coreLoadExecutor-5710-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=21, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.24594773439931555]
[junit4] 2> 427694 INFO (coreLoadExecutor-5710-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 427694 INFO (coreLoadExecutor-5710-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 427695 INFO (coreLoadExecutor-5710-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 427695 INFO (coreLoadExecutor-5710-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 427695 INFO (coreLoadExecutor-5710-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=45.10546875, floorSegmentMB=0.865234375, forceMergeDeletesPctAllowed=5.583252951334963, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.41583609520660425, deletesPctAllowed=46.076174037173615
[junit4] 2> 427696 INFO (coreLoadExecutor-5710-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 427698 INFO (coreLoadExecutor-5710-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 427707 INFO (coreLoadExecutor-5710-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 427707 INFO (coreLoadExecutor-5710-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1682066849279246336
[junit4] 2> 427708 INFO (searcherExecutor-5712-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 427708 INFO (searcherExecutor-5712-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 427708 INFO (searcherExecutor-5712-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 427708 INFO (searcherExecutor-5712-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 427708 INFO (searcherExecutor-5712-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 427709 INFO (searcherExecutor-5712-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 427709 INFO (searcherExecutor-5712-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 427709 INFO (searcherExecutor-5712-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 427709 INFO (searcherExecutor-5712-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 427710 INFO (searcherExecutor-5712-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 427802 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedMissingSort_74AC1D7E2A30F763-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 427803 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 427803 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 427803 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.e.j.s.Server jetty-9.4.32.v20200930; built: 2020-09-30T16:16:37.804Z; git: de97d26f7bd222a0e16831e353d702a7a422f711; jvm 13.0.2+8
[junit4] 2> 427804 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 427804 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 427804 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 427804 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@58c7a374{/kdby/i,null,AVAILABLE}
[junit4] 2> 427805 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1c3fde3{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:40141}
[junit4] 2> 427805 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.e.j.s.Server Started @427830ms
[junit4] 2> 427805 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema-distributed-missing-sort.xml, hostContext=/kdby/i, hostPort=40141, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedMissingSort_74AC1D7E2A30F763-001/tempDir-001/shard0/cores}
[junit4] 2> 427805 ERROR (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 427805 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 427805 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.8.0
[junit4] 2> 427805 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 427805 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 427805 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-31T11:32:32.734921Z
[junit4] 2> 427805 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedMissingSort_74AC1D7E2A30F763-001/tempDir-001/shard0/solr.xml
[junit4] 2> 427811 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 427811 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 427812 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@432ca8d6, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 427905 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 427906 WARN (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@29659b63[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 427906 WARN (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@29659b63[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 427907 WARN (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6a4cba7d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 427907 WARN (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6a4cba7d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 427908 WARN (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 427916 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 427916 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 427931 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@432ca8d6
[junit4] 2> 427939 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@432ca8d6
[junit4] 2> 427939 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@432ca8d6
[junit4] 2> 427940 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedMissingSort_74AC1D7E2A30F763-001/tempDir-001/shard0/cores
[junit4] 2> 427940 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 427943 INFO (coreLoadExecutor-5729-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0
[junit4] 2> 427961 INFO (coreLoadExecutor-5729-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test-distributed-missing-sort
[junit4] 2> 427966 INFO (coreLoadExecutor-5729-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test-distributed-missing-sort/1.6 with uniqueid field id
[junit4] 2> 427966 INFO (coreLoadExecutor-5729-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 427966 INFO (coreLoadExecutor-5729-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@432ca8d6
[junit4] 2> 427967 INFO (coreLoadExecutor-5729-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedMissingSort_74AC1D7E2A30F763-001/tempDir-001/shard0/cores/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedMissingSort_74AC1D7E2A30F763-001/tempDir-001/shard0/cores/collection1/data/]
[junit4] 2> 427968 INFO (coreLoadExecutor-5729-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=21, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.24594773439931555]
[junit4] 2> 428005 INFO (coreLoadExecutor-5729-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 428005 INFO (coreLoadExecutor-5729-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 428006 INFO (coreLoadExecutor-5729-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 428006 INFO (coreLoadExecutor-5729-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 428006 INFO (coreLoadExecutor-5729-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=45.10546875, floorSegmentMB=0.865234375, forceMergeDeletesPctAllowed=5.583252951334963, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.41583609520660425, deletesPctAllowed=46.076174037173615
[junit4] 2> 428007 INFO (coreLoadExecutor-5729-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 428009 INFO (coreLoadExecutor-5729-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 428018 INFO (coreLoadExecutor-5729-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 428018 INFO (coreLoadExecutor-5729-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1682066849605353472
[junit4] 2> 428018 INFO (searcherExecutor-5731-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 428019 INFO (searcherExecutor-5731-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 428019 INFO (searcherExecutor-5731-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 428019 INFO (searcherExecutor-5731-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 428019 INFO (searcherExecutor-5731-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 428019 INFO (searcherExecutor-5731-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 428019 INFO (TEST-TestDistributedMissingSort.test-seed#[74AC1D7E2A30F763]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 428019 INFO (searcherExecutor-5731-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 428019 INFO (searcherExecutor-5731-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 428020 INFO (searcherExecutor-5731-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 428020 INFO (searcherExecutor-5731-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 428026 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1682066849612693504)} 0 0
[junit4] 2> 428031 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1682066849618984960)} 0 0
[junit4] 2> 428032 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[1 (1682066849620033536)]} 0 0
[junit4] 2> 428033 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[1 (1682066849621082112)]} 0 0
[junit4] 2> 428033 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[2 (1682066849621082112)]} 0 0
[junit4] 2> 428034 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[2 (1682066849622130688)]} 0 0
[junit4] 2> 428034 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[3 (1682066849622130688)]} 0 0
[junit4] 2> 428034 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[3 (1682066849622130689)]} 0 0
[junit4] 2> 428034 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[4 (1682066849622130689)]} 0 0
[junit4] 2> 428035 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[4 (1682066849623179264)]} 0 0
[junit4] 2> 428035 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[5 (1682066849623179264)]} 0 0
[junit4] 2> 428035 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[5 (1682066849623179265)]} 0 0
[junit4] 2> 428035 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[6 (1682066849623179265)]} 0 0
[junit4] 2> 428036 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[6 (1682066849624227840)]} 0 0
[junit4] 2> 428036 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[7 (1682066849624227840)]} 0 0
[junit4] 2> 428036 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[7 (1682066849624227841)]} 0 0
[junit4] 2> 428036 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[8 (1682066849624227841)]} 0 0
[junit4] 2> 428037 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[8 (1682066849625276416)]} 0 0
[junit4] 2> 428037 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[9 (1682066849625276416)]} 0 0
[junit4] 2> 428037 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[9 (1682066849625276417)]} 0 0
[junit4] 2> 428042 INFO (searcherExecutor-5712-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 428042 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 4
[junit4] 2> 428047 INFO (searcherExecutor-5731-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 428047 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 4
[junit4] 2> 428049 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[10 (1682066849636810752)]} 0 0
[junit4] 2> 428049 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[10 (1682066849637859328)]} 0 0
[junit4] 2> 428050 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[11 (1682066849638907904)]} 0 0
[junit4] 2> 428050 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[11 (1682066849638907904)]} 0 0
[junit4] 2> 428050 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[12 (1682066849638907905)]} 0 0
[junit4] 2> 428050 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[12 (1682066849638907905)]} 0 0
[junit4] 2> 428051 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[13 (1682066849639956480)]} 0 0
[junit4] 2> 428051 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[13 (1682066849639956480)]} 0 0
[junit4] 2> 428051 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[14 (1682066849639956481)]} 0 0
[junit4] 2> 428051 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[14 (1682066849639956481)]} 0 0
[junit4] 2> 428052 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[15 (1682066849641005056)]} 0 0
[junit4] 2> 428052 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[15 (1682066849641005056)]} 0 0
[junit4] 2> 428052 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[16 (1682066849641005057)]} 0 0
[junit4] 2> 428052 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[16 (1682066849641005057)]} 0 0
[junit4] 2> 428053 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[17 (1682066849642053632)]} 0 0
[junit4] 2> 428053 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[17 (1682066849642053632)]} 0 0
[junit4] 2> 428053 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[100 (1682066849642053633)]} 0 0
[junit4] 2> 428053 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[100 (1682066849642053633)]} 0 0
[junit4] 2> 428054 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[101 (1682066849643102208)]} 0 0
[junit4] 2> 428054 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[101 (1682066849643102208)]} 0 0
[junit4] 2> 428054 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[102 (1682066849643102209)]} 0 0
[junit4] 2> 428054 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[102 (1682066849643102209)]} 0 0
[junit4] 2> 428055 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[103 (1682066849643102210)]} 0 0
[junit4] 2> 428055 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[103 (1682066849644150784)]} 0 0
[junit4] 2> 428055 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[104 (1682066849644150784)]} 0 0
[junit4] 2> 428055 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[104 (1682066849644150785)]} 0 0
[junit4] 2> 428056 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[105 (1682066849644150785)]} 0 0
[junit4] 2> 428056 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[105 (1682066849645199360)]} 0 0
[junit4] 2> 428056 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[106 (1682066849645199360)]} 0 0
[junit4] 2> 428056 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[106 (1682066849645199361)]} 0 0
[junit4] 2> 428056 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[107 (1682066849645199361)]} 0 0
[junit4] 2> 428057 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[107 (1682066849646247936)]} 0 0
[junit4] 2> 428057 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[108 (1682066849646247936)]} 0 0
[junit4] 2> 428057 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[108 (1682066849646247937)]} 0 0
[junit4] 2> 428057 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[109 (1682066849646247937)]} 0 0
[junit4] 2> 428058 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[109 (1682066849647296512)]} 0 0
[junit4] 2> 428058 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[110 (1682066849647296512)]} 0 0
[junit4] 2> 428058 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[110 (1682066849647296513)]} 0 0
[junit4] 2> 428058 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[111 (1682066849647296513)]} 0 0
[junit4] 2> 428059 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[111 (1682066849648345088)]} 0 0
[junit4] 2> 428059 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[112 (1682066849648345088)]} 0 0
[junit4] 2> 428059 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[112 (1682066849648345089)]} 0 0
[junit4] 2> 428059 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[113 (1682066849648345089)]} 0 0
[junit4] 2> 428060 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[113 (1682066849649393664)]} 0 0
[junit4] 2> 428060 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[114 (1682066849649393664)]} 0 0
[junit4] 2> 428060 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[114 (1682066849649393665)]} 0 0
[junit4] 2> 428060 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[115 (1682066849649393665)]} 0 0
[junit4] 2> 428060 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[115 (1682066849649393666)]} 0 0
[junit4] 2> 428061 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[116 (1682066849650442240)]} 0 0
[junit4] 2> 428061 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[116 (1682066849650442240)]} 0 0
[junit4] 2> 428061 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[117 (1682066849650442241)]} 0 0
[junit4] 2> 428061 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[117 (1682066849650442241)]} 0 0
[junit4] 2> 428062 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[118 (1682066849651490816)]} 0 0
[junit4] 2> 428062 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[118 (1682066849651490816)]} 0 0
[junit4] 2> 428062 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[119 (1682066849651490817)]} 0 0
[junit4] 2> 428062 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[119 (1682066849651490817)]} 0 0
[junit4] 2> 428063 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[120 (1682066849651490818)]} 0 0
[junit4] 2> 428063 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[120 (1682066849652539392)]} 0 0
[junit4] 2> 428063 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[121 (1682066849652539392)]} 0 0
[junit4] 2> 428063 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[121 (1682066849652539393)]} 0 0
[junit4] 2> 428063 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[122 (1682066849652539393)]} 0 0
[junit4] 2> 428064 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[122 (1682066849653587968)]} 0 0
[junit4] 2> 428064 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[123 (1682066849653587968)]} 0 0
[junit4] 2> 428064 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[123 (1682066849653587969)]} 0 0
[junit4] 2> 428065 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[124 (1682066849654636544)]} 0 0
[junit4] 2> 428065 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[124 (1682066849654636544)]} 0 0
[junit4] 2> 428065 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[125 (1682066849654636545)]} 0 0
[junit4] 2> 428066 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[125 (1682066849655685120)]} 0 0
[junit4] 2> 428066 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[126 (1682066849655685120)]} 0 0
[junit4] 2> 428066 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[126 (1682066849655685121)]} 0 0
[junit4] 2> 428066 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[127 (1682066849655685121)]} 0 0
[junit4] 2> 428067 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[127 (1682066849655685122)]} 0 0
[junit4] 2> 428067 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[128 (1682066849656733696)]} 0 0
[junit4] 2> 428067 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[128 (1682066849656733696)]} 0 0
[junit4] 2> 428067 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[129 (1682066849656733697)]} 0 0
[junit4] 2> 428067 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[129 (1682066849656733697)]} 0 0
[junit4] 2> 428068 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[130 (1682066849657782272)]} 0 0
[junit4] 2> 428068 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[130 (1682066849657782272)]} 0 0
[junit4] 2> 428068 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[131 (1682066849657782273)]} 0 0
[junit4] 2> 428068 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[131 (1682066849657782273)]} 0 0
[junit4] 2> 428069 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[132 (1682066849658830848)]} 0 0
[junit4] 2> 428069 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[132 (1682066849658830848)]} 0 0
[junit4] 2> 428069 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[133 (1682066849658830849)]} 0 0
[junit4] 2> 428069 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[133 (1682066849658830849)]} 0 0
[junit4] 2> 428070 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[134 (1682066849659879424)]} 0 0
[junit4] 2> 428070 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[134 (1682066849659879424)]} 0 0
[junit4] 2> 428070 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[135 (1682066849659879425)]} 0 0
[junit4] 2> 428071 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[135 (1682066849660928000)]} 0 0
[junit4] 2> 428071 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[136 (1682066849660928000)]} 0 0
[junit4] 2> 428071 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[136 (1682066849660928001)]} 0 0
[junit4] 2> 428072 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[137 (1682066849661976576)]} 0 0
[junit4] 2> 428072 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[137 (1682066849661976576)]} 0 0
[junit4] 2> 428072 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[138 (1682066849661976577)]} 0 0
[junit4] 2> 428072 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[138 (1682066849661976577)]} 0 0
[junit4] 2> 428073 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[139 (1682066849663025152)]} 0 0
[junit4] 2> 428073 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[139 (1682066849663025152)]} 0 0
[junit4] 2> 428073 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[140 (1682066849663025153)]} 0 0
[junit4] 2> 428073 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[140 (1682066849663025153)]} 0 0
[junit4] 2> 428074 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[141 (1682066849664073728)]} 0 0
[junit4] 2> 428074 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[141 (1682066849664073728)]} 0 0
[junit4] 2> 428074 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[142 (1682066849664073729)]} 0 0
[junit4] 2> 428074 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[142 (1682066849664073729)]} 0 0
[junit4] 2> 428074 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[143 (1682066849664073730)]} 0 0
[junit4] 2> 428075 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[143 (1682066849665122304)]} 0 0
[junit4] 2> 428075 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[144 (1682066849665122304)]} 0 0
[junit4] 2> 428075 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[144 (1682066849665122305)]} 0 0
[junit4] 2> 428075 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[145 (1682066849665122305)]} 0 0
[junit4] 2> 428076 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[145 (1682066849666170880)]} 0 0
[junit4] 2> 428076 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[146 (1682066849666170880)]} 0 0
[junit4] 2> 428076 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[146 (1682066849666170881)]} 0 0
[junit4] 2> 428076 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[147 (1682066849666170881)]} 0 0
[junit4] 2> 428076 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[147 (1682066849666170882)]} 0 0
[junit4] 2> 428077 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[148 (1682066849667219456)]} 0 0
[junit4] 2> 428077 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[148 (1682066849667219456)]} 0 0
[junit4] 2> 428077 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[149 (1682066849667219457)]} 0 0
[junit4] 2> 428077 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={wt=javabin&version=2}{add=[149 (1682066849667219457)]} 0 0
[junit4] 2> 428082 INFO (searcherExecutor-5712-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 428082 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 4
[junit4] 2> 428088 INFO (searcherExecutor-5731-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 428089 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/kdby/i path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 5
[junit4] 2> 428090 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&distrib=false&sort=one_i1_ml+desc&rows=13&wt=javabin&version=2} hits=67 status=0 QTime=0
[junit4] 2> 428109 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=one_i1_ml+desc&shard.url=[::1]:8/kdby/i|[::1]:4/kdby/i|[::1]:6/kdby/i|https://127.0.0.1:40141/kdby/i/collection1&rows=13&rid=127.0.0.1-76452&version=2&q=*:*&omitHeader=false&NOW=1604143953019&isShard=true&wt=javabin} hits=67 status=0 QTime=0
[junit4] 2> 428111 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&shards.purpose=64&shard.url=[::1]:8/kdby/i|[::1]:4/kdby/i|[::1]:6/kdby/i|https://127.0.0.1:40141/kdby/i/collection1&rows=13&rid=127.0.0.1-76452&version=2&q=*:*&omitHeader=false&NOW=1604143953019&ids=11,12,13,1,2,3,4,5,6,7,8,9,10&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 428111 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&shards=[::1]:8/kdby/i|[::1]:4/kdby/i|[::1]:6/kdby/i|https://127.0.0.1:40141/kdby/i/collection1&sort=one_i1_ml+desc&rows=13&wt=javabin&version=2} rid=127.0.0.1-76452 hits=67 status=0 QTime=20
[junit4] 2> 428112 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&distrib=false&sort=one_i1_ml+asc&rows=13&wt=javabin&version=2} hits=67 status=0 QTime=0
[junit4] 2> 428113 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=one_i1_ml+asc&shard.url=https://127.0.0.1:40141/kdby/i/collection1|[::1]:4/kdby/i|[::1]:8/kdby/i&rows=13&rid=127.0.0.1-76454&version=2&q=*:*&omitHeader=false&NOW=1604143953042&isShard=true&wt=javabin} hits=67 status=0 QTime=0
[junit4] 2> 428114 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&shards.purpose=64&shard.url=https://127.0.0.1:40141/kdby/i/collection1|[::1]:4/kdby/i|[::1]:8/kdby/i&rows=13&rid=127.0.0.1-76454&version=2&q=*:*&omitHeader=false&NOW=1604143953042&ids=11,12,13,1,2,3,4,5,6,7,8,9,10&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 428114 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&shards=https://127.0.0.1:40141/kdby/i/collection1|[::1]:4/kdby/i|[::1]:8/kdby/i&sort=one_i1_ml+asc&rows=13&wt=javabin&version=2} rid=127.0.0.1-76454 hits=67 status=0 QTime=1
[junit4] 2> 428115 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&distrib=false&sort=one_i1_ml+desc,+id_i+asc&rows=200&wt=javabin&version=2} hits=67 status=0 QTime=0
[junit4] 2> 428117 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=one_i1_ml+desc,+id_i+asc&shard.url=[::1]:4/kdby/i|https://127.0.0.1:40141/kdby/i/collection1|[::1]:6/kdby/i&rows=200&rid=127.0.0.1-76456&version=2&q=*:*&omitHeader=false&NOW=1604143953045&isShard=true&wt=javabin} hits=67 status=0 QTime=0
[junit4] 2> 428118 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&shards.purpose=64&shard.url=[::1]:4/kdby/i|https://127.0.0.1:40141/kdby/i/collection1|[::1]:6/kdby/i&rows=200&rid=127.0.0.1-76456&version=2&q=*:*&omitHeader=false&NOW=1604143953045&ids=110,111,112,113,114,115,116,117,118,119,10,11,12,13,14,15,16,17,120,121,122,1,123,2,124,3,125,4,126,5,127,6,128,7,129,8,9,130,131,132,133,134,135,136,137,138,139,140,141,142,143,144,100,145,101,146,102,147,103,148,104,149,105,106,107,108,109&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 428119 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&shards=[::1]:4/kdby/i|https://127.0.0.1:40141/kdby/i/collection1|[::1]:6/kdby/i&sort=one_i1_ml+desc,+id_i+asc&rows=200&wt=javabin&version=2} rid=127.0.0.1-76456 hits=67 status=0 QTime=3
[junit4] 2> 428120 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&distrib=false&sort=one_i1_ml+asc,+id_i+desc&rows=200&wt=javabin&version=2} hits=67 status=0 QTime=0
[junit4] 2> 428122 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=one_i1_ml+asc,+id_i+desc&shard.url=[::1]:8/kdby/i|[::1]:6/kdby/i|[::1]:4/kdby/i|https://127.0.0.1:40141/kdby/i/collection1&rows=200&rid=127.0.0.1-76458&version=2&q=*:*&omitHeader=false&NOW=1604143953050&isShard=true&wt=javabin} hits=67 status=0 QTime=0
[junit4] 2> 428123 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&shards.purpose=64&shard.url=[::1]:8/kdby/i|[::1]:6/kdby/i|[::1]:4/kdby/i|https://127.0.0.1:40141/kdby/i/collection1&rows=200&rid=127.0.0.1-76458&version=2&q=*:*&omitHeader=false&NOW=1604143953050&ids=110,111,112,113,114,115,116,117,118,119,10,11,12,13,14,15,16,17,120,121,122,1,123,2,124,3,125,4,126,5,127,6,128,7,129,8,9,130,131,132,133,134,135,136,137,138,139,140,141,142,143,100,144,101,145,102,146,103,147,104,148,105,149,106,107,108,109&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 428124 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&shards=[::1]:8/kdby/i|[::1]:6/kdby/i|[::1]:4/kdby/i|https://127.0.0.1:40141/kdby/i/collection1&sort=one_i1_ml+asc,+id_i+desc&rows=200&wt=javabin&version=2} rid=127.0.0.1-76458 hits=67 status=0 QTime=2
[junit4] 2> 428125 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&distrib=false&sort=three_l1_ml+desc&rows=13&wt=javabin&version=2} hits=67 status=0 QTime=0
[junit4] 2> 428126 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=three_l1_ml+desc&shard.url=[::1]:4/kdby/i|https://127.0.0.1:40141/kdby/i/collection1&rows=13&rid=127.0.0.1-76460&version=2&q=*:*&omitHeader=false&NOW=1604143953054&isShard=true&wt=javabin} hits=67 status=0 QTime=0
[junit4] 2> 428127 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&shards.purpose=64&shard.url=[::1]:4/kdby/i|https://127.0.0.1:40141/kdby/i/collection1&rows=13&rid=127.0.0.1-76460&version=2&q=*:*&omitHeader=false&NOW=1604143953054&ids=11,12,13,1,2,3,4,5,6,7,8,9,10&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 428127 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&shards=[::1]:4/kdby/i|https://127.0.0.1:40141/kdby/i/collection1&sort=three_l1_ml+desc&rows=13&wt=javabin&version=2} rid=127.0.0.1-76460 hits=67 status=0 QTime=1
[junit4] 2> 428128 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&distrib=false&sort=three_l1_ml+asc&rows=13&wt=javabin&version=2} hits=67 status=0 QTime=0
[junit4] 2> 428128 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=three_l1_ml+asc&shard.url=[::1]:4/kdby/i|https://127.0.0.1:40141/kdby/i/collection1|[::1]:8/kdby/i|[::1]:6/kdby/i&rows=13&rid=127.0.0.1-76462&version=2&q=*:*&omitHeader=false&NOW=1604143953057&isShard=true&wt=javabin} hits=67 status=0 QTime=0
[junit4] 2> 428129 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&shards.purpose=64&shard.url=[::1]:4/kdby/i|https://127.0.0.1:40141/kdby/i/collection1|[::1]:8/kdby/i|[::1]:6/kdby/i&rows=13&rid=127.0.0.1-76462&version=2&q=*:*&omitHeader=false&NOW=1604143953057&ids=11,12,13,1,2,3,4,5,6,7,8,9,10&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 428129 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&shards=[::1]:4/kdby/i|https://127.0.0.1:40141/kdby/i/collection1|[::1]:8/kdby/i|[::1]:6/kdby/i&sort=three_l1_ml+asc&rows=13&wt=javabin&version=2} rid=127.0.0.1-76462 hits=67 status=0 QTime=1
[junit4] 2> 428130 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&distrib=false&sort=three_l1_ml+desc,+id_i+asc&rows=200&wt=javabin&version=2} hits=67 status=0 QTime=0
[junit4] 2> 428131 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=three_l1_ml+desc,+id_i+asc&shard.url=https://127.0.0.1:40141/kdby/i/collection1&rows=200&rid=127.0.0.1-76464&version=2&q=*:*&omitHeader=false&NOW=1604143953060&isShard=true&wt=javabin} hits=67 status=0 QTime=0
[junit4] 2> 428132 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&shards.purpose=64&shard.url=https://127.0.0.1:40141/kdby/i/collection1&rows=200&rid=127.0.0.1-76464&version=2&q=*:*&omitHeader=false&NOW=1604143953060&ids=110,111,112,113,114,115,116,117,118,119,10,11,12,13,14,15,16,17,120,121,122,1,123,2,124,3,125,4,126,5,127,6,128,7,129,8,9,130,131,132,133,134,135,136,137,138,139,140,141,142,143,144,100,145,101,146,102,147,103,148,104,149,105,106,107,108,109&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 428132 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&shards=https://127.0.0.1:40141/kdby/i/collection1&sort=three_l1_ml+desc,+id_i+asc&rows=200&wt=javabin&version=2} rid=127.0.0.1-76464 hits=67 status=0 QTime=1
[junit4] 2> 428133 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&distrib=false&sort=three_l1_ml+asc,+id_i+desc&rows=200&wt=javabin&version=2} hits=67 status=0 QTime=0
[junit4] 2> 428135 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=three_l1_ml+asc,+id_i+desc&shard.url=https://127.0.0.1:40141/kdby/i/collection1|[::1]:8/kdby/i|[::1]:6/kdby/i&rows=200&rid=127.0.0.1-76466&version=2&q=*:*&omitHeader=false&NOW=1604143953063&isShard=true&wt=javabin} hits=67 status=0 QTime=0
[junit4] 2> 428140 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&shards.purpose=64&shard.url=https://127.0.0.1:40141/kdby/i/collection1|[::1]:8/kdby/i|[::1]:6/kdby/i&rows=200&rid=127.0.0.1-76466&version=2&q=*:*&omitHeader=false&NOW=1604143953063&ids=110,111,112,113,114,115,116,117,118,119,10,11,12,13,14,15,16,17,120,121,122,1,123,2,124,3,125,4,126,5,127,6,128,7,129,8,9,130,131,132,133,134,135,136,137,138,139,140,141,142,143,100,144,101,145,102,146,103,147,104,148,105,149,106,107,108,109&isShard=true&wt=javabin} status=0 QTime=5
[junit4] 2> 428142 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&shards=https://127.0.0.1:40141/kdby/i/collection1|[::1]:8/kdby/i|[::1]:6/kdby/i&sort=three_l1_ml+asc,+id_i+desc&rows=200&wt=javabin&version=2} rid=127.0.0.1-76466 hits=67 status=0 QTime=7
[junit4] 2> 428143 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&distrib=false&sort=five_s1_ml+desc&rows=13&wt=javabin&version=2} hits=67 status=0 QTime=0
[junit4] 2> 428144 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=five_s1_ml+desc&shard.url=[::1]:8/kdby/i|https://127.0.0.1:40141/kdby/i/collection1|[::1]:4/kdby/i|[::1]:6/kdby/i&rows=13&rid=127.0.0.1-76468&version=2&q=*:*&omitHeader=false&NOW=1604143953073&isShard=true&wt=javabin} hits=67 status=0 QTime=0
[junit4] 2> 428145 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&shards.purpose=64&shard.url=[::1]:8/kdby/i|https://127.0.0.1:40141/kdby/i/collection1|[::1]:4/kdby/i|[::1]:6/kdby/i&rows=13&rid=127.0.0.1-76468&version=2&q=*:*&omitHeader=false&NOW=1604143953073&ids=11,12,13,1,2,3,4,5,6,7,8,9,10&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 428145 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&shards=[::1]:8/kdby/i|https://127.0.0.1:40141/kdby/i/collection1|[::1]:4/kdby/i|[::1]:6/kdby/i&sort=five_s1_ml+desc&rows=13&wt=javabin&version=2} rid=127.0.0.1-76468 hits=67 status=0 QTime=1
[junit4] 2> 428146 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&distrib=false&sort=five_s1_ml+asc&rows=13&wt=javabin&version=2} hits=67 status=0 QTime=0
[junit4] 2> 428147 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=five_s1_ml+asc&shard.url=https://127.0.0.1:40141/kdby/i/collection1|[::1]:4/kdby/i|[::1]:8/kdby/i&rows=13&rid=127.0.0.1-76470&version=2&q=*:*&omitHeader=false&NOW=1604143953076&isShard=true&wt=javabin} hits=67 status=0 QTime=0
[junit4] 2> 428149 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&shards.purpose=64&shard.url=https://127.0.0.1:40141/kdby/i/collection1|[::1]:4/kdby/i|[::1]:8/kdby/i&rows=13&rid=127.0.0.1-76470&version=2&q=*:*&omitHeader=false&NOW=1604143953076&ids=11,12,13,1,2,3,4,5,6,7,8,9,10&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 428152 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&shards=https://127.0.0.1:40141/kdby/i/collection1|[::1]:4/kdby/i|[::1]:8/kdby/i&sort=five_s1_ml+asc&rows=13&wt=javabin&version=2} rid=127.0.0.1-76470 hits=67 status=0 QTime=5
[junit4] 2> 428153 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&distrib=false&sort=five_s1_ml+desc,+id_i+asc&rows=200&wt=javabin&version=2} hits=67 status=0 QTime=0
[junit4] 2> 428155 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=five_s1_ml+desc,+id_i+asc&shard.url=[::1]:8/kdby/i|https://127.0.0.1:40141/kdby/i/collection1&rows=200&rid=127.0.0.1-76472&version=2&q=*:*&omitHeader=false&NOW=1604143953084&isShard=true&wt=javabin} hits=67 status=0 QTime=0
[junit4] 2> 428156 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&shards.purpose=64&shard.url=[::1]:8/kdby/i|https://127.0.0.1:40141/kdby/i/collection1&rows=200&rid=127.0.0.1-76472&version=2&q=*:*&omitHeader=false&NOW=1604143953084&ids=110,111,112,113,114,115,116,117,118,119,10,11,12,13,14,15,16,17,120,121,122,1,123,2,124,3,125,4,126,5,127,6,128,7,129,8,9,130,131,132,133,134,135,136,137,138,139,140,141,142,143,144,100,145,101,146,102,147,103,148,104,149,105,106,107,108,109&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 428157 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&shards=[::1]:8/kdby/i|https://127.0.0.1:40141/kdby/i/collection1&sort=five_s1_ml+desc,+id_i+asc&rows=200&wt=javabin&version=2} rid=127.0.0.1-76472 hits=67 status=0 QTime=2
[junit4] 2> 428158 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&distrib=false&sort=five_s1_ml+asc,+id_i+desc&rows=200&wt=javabin&version=2} hits=67 status=0 QTime=0
[junit4] 2> 428160 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=five_s1_ml+asc,+id_i+desc&shard.url=https://127.0.0.1:40141/kdby/i/collection1&rows=200&rid=127.0.0.1-76474&version=2&q=*:*&omitHeader=false&NOW=1604143953088&isShard=true&wt=javabin} hits=67 status=0 QTime=0
[junit4] 2> 428161 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&shards.purpose=64&shard.url=https://127.0.0.1:40141/kdby/i/collection1&rows=200&rid=127.0.0.1-76474&version=2&q=*:*&omitHeader=false&NOW=1604143953088&ids=110,111,112,113,114,115,116,117,118,119,10,11,12,13,14,15,16,17,120,121,122,1,123,2,124,3,125,4,126,5,127,6,128,7,129,8,9,130,131,132,133,134,135,136,137,138,139,140,141,142,143,100,144,101,145,102,146,103,147,104,148,105,149,106,107,108,109&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 428161 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&shards=https://127.0.0.1:40141/kdby/i/collection1&sort=five_s1_ml+asc,+id_i+desc&rows=200&wt=javabin&version=2} rid=127.0.0.1-76474 hits=67 status=0 QTime=2
[junit4] 2> 428162 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&distrib=false&sort=two_i1_mf+desc,+id_i+asc&rows=200&wt=javabin&version=2} hits=67 status=0 QTime=0
[junit4] 2> 428167 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=two_i1_mf+desc,+id_i+asc&shard.url=[::1]:8/kdby/i|[::1]:4/kdby/i|[::1]:6/kdby/i|https://127.0.0.1:40141/kdby/i/collection1&rows=200&rid=127.0.0.1-76476&version=2&q=*:*&omitHeader=false&NOW=1604143953092&isShard=true&wt=javabin} hits=67 status=0 QTime=0
[junit4] 2> 428168 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&shards.purpose=64&shard.url=[::1]:8/kdby/i|[::1]:4/kdby/i|[::1]:6/kdby/i|https://127.0.0.1:40141/kdby/i/collection1&rows=200&rid=127.0.0.1-76476&version=2&q=*:*&omitHeader=false&NOW=1604143953092&ids=110,111,112,113,114,115,116,117,118,119,10,11,12,13,14,15,16,17,120,121,1,122,2,123,3,124,4,125,5,126,6,127,7,128,8,129,9,130,131,132,133,134,135,136,137,138,139,140,141,142,143,144,100,145,101,146,102,147,103,148,104,149,105,106,107,108,109&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 428168 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&shards=[::1]:8/kdby/i|[::1]:4/kdby/i|[::1]:6/kdby/i|https://127.0.0.1:40141/kdby/i/collection1&sort=two_i1_mf+desc,+id_i+asc&rows=200&wt=javabin&version=2} rid=127.0.0.1-76476 hits=67 status=0 QTime=5
[junit4] 2> 428169 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&distrib=false&sort=two_i1_mf+asc,+id_i+desc&rows=200&wt=javabin&version=2} hits=67 status=0 QTime=0
[junit4] 2> 428171 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=two_i1_mf+asc,+id_i+desc&shard.url=[::1]:4/kdby/i|[::1]:8/kdby/i|https://127.0.0.1:40141/kdby/i/collection1|[::1]:6/kdby/i&rows=200&rid=127.0.0.1-76478&version=2&q=*:*&omitHeader=false&NOW=1604143953099&isShard=true&wt=javabin} hits=67 status=0 QTime=0
[junit4] 2> 428172 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&shards.purpose=64&shard.url=[::1]:4/kdby/i|[::1]:8/kdby/i|https://127.0.0.1:40141/kdby/i/collection1|[::1]:6/kdby/i&rows=200&rid=127.0.0.1-76478&version=2&q=*:*&omitHeader=false&NOW=1604143953099&ids=110,111,112,113,114,115,116,117,118,119,10,11,12,13,14,15,16,17,120,121,1,122,2,123,3,124,4,125,5,126,6,127,7,128,8,129,9,130,131,132,133,134,135,136,137,138,139,140,141,142,143,100,144,101,145,102,146,103,147,104,148,105,149,106,107,108,109&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 428173 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&shards=[::1]:4/kdby/i|[::1]:8/kdby/i|https://127.0.0.1:40141/kdby/i/collection1|[::1]:6/kdby/i&sort=two_i1_mf+asc,+id_i+desc&rows=200&wt=javabin&version=2} rid=127.0.0.1-76478 hits=67 status=0 QTime=2
[junit4] 2> 428174 INFO (qtp2055991899-10731) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&distrib=false&sort=four_l1_mf+desc,+id_i+asc&rows=200&wt=javabin&version=2} hits=67 status=0 QTime=0
[junit4] 2> 428176 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=four_l1_mf+desc,+id_i+asc&shard.url=https://127.0.0.1:40141/kdby/i/collection1|[::1]:8/kdby/i|[::1]:4/kdby/i|[::1]:6/kdby/i&rows=200&rid=127.0.0.1-76480&version=2&q=*:*&omitHeader=false&NOW=1604143953104&isShard=true&wt=javabin} hits=67 status=0 QTime=0
[junit4] 2> 428177 INFO (qtp768002302-10736) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&shards.purpose=64&shard.url=https://127.0.0.1:40141/kdby/i/collection1|[::1]:8/kdby/i|[::1]:4/kdby/i|[::1]:6/kdby/i&rows=200&rid=127.0.0.1-76480&version=2&q=*:*&omitHeader=false&NOW=1604143953104&ids=110,111,112,113,114,115,116,117,118,119,10,11,12,13,14,15,16,17,120,121,1,122,2,123,3,124,4,125,5,126,6,127,7,128,8,129,9,130,131,132,133,134,135,136,137,138,139,140,141,142,143,144,100,145,101,146,102,147,103,148,104,149,105,106,107,108,109&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 428178 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&shards=https://127.0.0.1:40141/kdby/i/collection1|[::1]:8/kdby/i|[::1]:4/kdby/i|[::1]:6/kdby/i&sort=four_l1_mf+desc,+id_i+asc&rows=200&wt=javabin&version=2} rid=127.0.0.1-76480 hits=67 status=0 QTime=3
[junit4] 2> 428179 INFO (qtp2055991899-10733) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={q=*:*&distrib=false&sort=four_l1_mf+asc,+id_i+desc&rows=200&wt=javabin&version=2} hits=67 status=0 QTime=0
[junit4] 2> 428181 INFO (qtp768002302-10734) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp=/kdby/i path=/select params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=four_l1_mf+asc,+id_i+desc&shard.url=[::1]:4/kdby/i|[::1]:8/kdby/i|https://127.0.0.1:40141/kdby/i/collection1|[::1]:6/kdby/i&rows=200&rid=127.0.0.1-76482&version=2&q=*:*&omitHeader=false&NOW=1604143953109&isShard=true&wt=javabin} hits=67 status=0 QTime=0
[...truncated too long message...]
Stopped o.e.j.s.ServletContextHandler@83dc401{/kdby/i,null,STOPPED}
[junit4] 2> 429506 INFO (closeThreadPool-5805-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 429508 INFO (coreCloseExecutor-5814-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 429517 INFO (closeThreadPool-5805-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 429517 INFO (closeThreadPool-5805-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@70cff94d: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@68552c6c
[junit4] 2> 429520 INFO (closeThreadPool-5805-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 429520 INFO (closeThreadPool-5805-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6b25a638: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@40c598eb
[junit4] 2> 429522 INFO (closeThreadPool-5805-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 429522 INFO (closeThreadPool-5805-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2bbd0df: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@527ec726
[junit4] 2> 429525 INFO (closeThreadPool-5805-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@676ee32e{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
[junit4] 2> 429525 INFO (closeThreadPool-5805-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@acae716{/kdby/i,null,STOPPED}
[junit4] 2> 429525 INFO (closeThreadPool-5805-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedMissingSort -Dtests.method=test -Dtests.seed=74AC1D7E2A30F763 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-UG -Dtests.timezone=America/Merida -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 2.04s J2 | TestDistributedMissingSort.test <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:35143/kdby/i/collection1: org.apache.solr.client.solrj.SolrServerException: java.lang.ArithmeticException: / by zero
[junit4] > at __randomizedtesting.SeedInfo.seed([74AC1D7E2A30F763:FCF822A484CC9A9B]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:681)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:214)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1003)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1018)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:625)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:677)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:655)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:634)
[junit4] > at org.apache.solr.TestDistributedMissingSort.testSortMissingLast(TestDistributedMissingSort.java:175)
[junit4] > at org.apache.solr.TestDistributedMissingSort.test(TestDistributedMissingSort.java:43)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:567)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1117)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:830)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedMissingSort_74AC1D7E2A30F763-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {six_s1_mf=PostingsFormat(name=Asserting), rnd_b=PostingsFormat(name=Asserting), SubjectTerms_mfacet=Lucene84, foo_b=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), id=PostingsFormat(name=Asserting), five_s1_ml=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84))}, docValues:{two_i1_mf=DocValuesFormat(name=Direct), one_i1_ml=DocValuesFormat(name=Direct), n_l1=DocValuesFormat(name=Lucene80), n_dt1=DocValuesFormat(name=Lucene80), n_td1=DocValuesFormat(name=Lucene80), n_d1=DocValuesFormat(name=Lucene80), n_f1=DocValuesFormat(name=Lucene80), n_ti1=DocValuesFormat(name=Asserting), n_tl1=DocValuesFormat(name=Lucene80), id_i=DocValuesFormat(name=Direct), _version_=DocValuesFormat(name=Lucene80), n_tf1=DocValuesFormat(name=Lucene80), n_tdt1=DocValuesFormat(name=Lucene80), foo_d=DocValuesFormat(name=Asserting), three_l1_ml=DocValuesFormat(name=Lucene80), foo_f=DocValuesFormat(name=Direct), four_l1_mf=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=2046, maxMBSortInHeap=6.553148565396194, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=en-UG, timezone=America/Merida
[junit4] 2> NOTE: Linux 5.4.0-52-generic amd64/AdoptOpenJDK 13.0.2 (64-bit)/cpus=16,threads=1,free=144568736,total=527433728
[junit4] 2> NOTE: All tests run in this JVM: [TestFunctionQuery, SolrCoreCheckLockOnStartupTest, HttpSolrCallGetCoreTest, IndexSchemaTest, BlockJoinFacetDistribTest, TestDistributedGrouping, TestRemoteStreaming, JSONWriterTest, RandomizedTaggerTest, TestShortCircuitedRequests, MetricsHistoryHandlerTest, SuggestComponentTest, TestSimDistributedQueue, TestFieldTypeResource, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestLegacyFieldReuse, ConfigureRecoveryStrategyTest, HdfsDirectoryTest, FacetPivotSmallTest, TestSearcherReuse, TestBlobHandler, AtomicUpdateRemovalJavabinTest, AnalyticsMergeStrategyTest, TestHdfsCloudBackupRestore, TestCaffeineCache, CoreAdminHandlerTest, SplitHandlerTest, DistribJoinFromCollectionTest, TestHttpServletCarrier, TriggerEventQueueTest, HdfsThreadLeakTest, HdfsCollectionsAPIDistributedZkTest, TestEmbeddedSolrServerConstructors, TestSimNodeLostTrigger, TestNumericTokenStream, TestSolrCoreParser, V2StandaloneTest, TestInPlaceUpdatesDistrib, TestDynamicFieldNamesIndexCorrectly, TestLuceneMatchVersion, TestCorePropertiesReload, NodeLostTriggerIntegrationTest, CircularListTest, TemplateUpdateProcessorTest, DeleteNodeTest, TestSubQueryTransformerCrossCore, TransactionLogTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestJoin, TestConfigSetsAPIZkFailure, MoveReplicaTest, TestMultiWordSynonyms, DirectUpdateHandlerTest, RangeFacetCloudTest, DistributedQueryComponentCustomSortTest, LeaderElectionTest, CloneFieldUpdateProcessorFactoryTest, ReturnFieldsTest, TestQuerySenderListener, ShardRoutingCustomTest, SolrMetricManagerTest, TestTolerantUpdateProcessorRandomCloud, TestTermsQParserPlugin, TestFieldCacheSort, AutoScalingHandlerTest, OverseerStatusTest, TestManagedSchemaAPI, IndexSizeTriggerSizeEstimationTest, DocValuesMissingTest, HdfsChaosMonkeySafeLeaderTest, TestSafeXMLParsing, HighlighterConfigTest, NumberUtilsTest, TestRealTimeGet, SolrIndexMetricsTest, BasicFunctionalityTest, CursorPagingTest, EchoParamsTest, MinimalSchemaTest, OutputWriterTest, SampleTest, SolrInfoBeanTest, SolrTestCaseJ4Test, TestDistributedMissingSort]
[junit4] Completed [401/921 (1!)] on J2 in 2.26s, 1 test, 1 error <<< FAILURES!
[...truncated 49449 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/4854/consoleText
[repro] Revision: 5b79ad3d64638556b04528bd941be3e41fc66a3a
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC"
[repro] Repro line: ant test -Dtestcase=TestDistributedMissingSort -Dtests.method=test -Dtests.seed=74AC1D7E2A30F763 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-UG -Dtests.timezone=America/Merida -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TestDistributedMissingSort
[repro] ant compile-test
[...truncated 3455 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestDistributedMissingSort" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=74AC1D7E2A30F763 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-UG -Dtests.timezone=America/Merida -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[...truncated 81 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.TestDistributedMissingSort
[repro] Exiting with code 0
[...truncated 64 lines...]
[JENKINS-Experimental-GC] Lucene-Solr-8.x-Linux (64bit/jdk-11.0.6)
- Build # 4855 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/4855/
Java: 64bit/jdk-11.0.6 -XX:-UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC
1 tests failed.
FAILED: org.apache.solr.pkg.TestPackages.testPluginLoading
Error Message:
req: org.apache.solr.filestore.TestDistribPackageStore$$Lambda$1302/0x00007fd4bd6dd8b0@281ed054 . attempt: 9 Mismatch for value : '[config, queryResponseWriter, json1, _packageinfo_, package]' in response , { "responseHeader":{ "status":0, "QTime":0}, "config":{"queryResponseWriter":{"json1":{ "name":"json1", "class":"mypkg:org.apache.solr.core.RuntimeLibResponseWriter"}}}}
Stack Trace:
java.lang.AssertionError: req: org.apache.solr.filestore.TestDistribPackageStore$$Lambda$1302/0x00007fd4bd6dd8b0@281ed054 . attempt: 9 Mismatch for value : '[config, queryResponseWriter, json1, _packageinfo_, package]' in response , {
"responseHeader":{
"status":0,
"QTime":0},
"config":{"queryResponseWriter":{"json1":{
"name":"json1",
"class":"mypkg:org.apache.solr.core.RuntimeLibResponseWriter"}}}}
at __randomizedtesting.SeedInfo.seed([8DF3F9FC89E1D363:5B7519016AFF14F9]:0)
at org.junit.Assert.fail(Assert.java:89)
at org.apache.solr.filestore.TestDistribPackageStore.assertResponseValues(TestDistribPackageStore.java:257)
at org.apache.solr.filestore.TestDistribPackageStore.assertResponseValues(TestDistribPackageStore.java:224)
at org.apache.solr.pkg.TestPackages.verifyComponent(TestPackages.java:476)
at org.apache.solr.pkg.TestPackages.testPluginLoading(TestPackages.java:193)
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:566)
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.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
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.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
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:834)
Build Log:
[...truncated 14260 lines...]
[junit4] Suite: org.apache.solr.pkg.TestPackages
[junit4] 2> 229436 INFO (SUITE-TestPackages-seed#[8DF3F9FC89E1D363]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/server/solr/configsets/_default/conf'
[junit4] 2> 229436 INFO (SUITE-TestPackages-seed#[8DF3F9FC89E1D363]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 229437 INFO (SUITE-TestPackages-seed#[8DF3F9FC89E1D363]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_8DF3F9FC89E1D363-001/data-dir-17-001
[junit4] 2> 229437 WARN (SUITE-TestPackages-seed#[8DF3F9FC89E1D363]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=25 numCloses=25
[junit4] 2> 229437 INFO (SUITE-TestPackages-seed#[8DF3F9FC89E1D363]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 229438 INFO (SUITE-TestPackages-seed#[8DF3F9FC89E1D363]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 229440 INFO (TEST-TestPackages.testPluginLoading-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testPluginLoading
[junit4] 2> 229441 INFO (TEST-TestPackages.testPluginLoading-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_8DF3F9FC89E1D363-001/tempDir-001
[junit4] 2> 229441 INFO (TEST-TestPackages.testPluginLoading-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 229441 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 229441 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 229442 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 229541 INFO (TEST-TestPackages.testPluginLoading-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.c.ZkTestServer start zk server on port: 45921
[junit4] 2> 229542 INFO (TEST-TestPackages.testPluginLoading-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:45921
[junit4] 2> 229542 INFO (TEST-TestPackages.testPluginLoading-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:45921
[junit4] 2> 229542 INFO (TEST-TestPackages.testPluginLoading-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 45921
[junit4] 2> 229543 INFO (TEST-TestPackages.testPluginLoading-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 229544 INFO (zkConnectionManagerCallback-1723-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 229544 INFO (TEST-TestPackages.testPluginLoading-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 229546 INFO (TEST-TestPackages.testPluginLoading-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 229547 INFO (zkConnectionManagerCallback-1725-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 229547 INFO (TEST-TestPackages.testPluginLoading-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 229548 INFO (TEST-TestPackages.testPluginLoading-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 229549 INFO (zkConnectionManagerCallback-1727-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 229549 INFO (TEST-TestPackages.testPluginLoading-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 229665 INFO (jetty-launcher-1728-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 229665 INFO (jetty-launcher-1728-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 229665 INFO (jetty-launcher-1728-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 229665 INFO (jetty-launcher-1728-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 229665 INFO (jetty-launcher-1728-thread-2) [ ] o.e.j.s.Server jetty-9.4.32.v20200930; built: 2020-09-30T16:16:37.804Z; git: de97d26f7bd222a0e16831e353d702a7a422f711; jvm 11.0.6+10
[junit4] 2> 229665 INFO (jetty-launcher-1728-thread-4) [ ] o.e.j.s.Server jetty-9.4.32.v20200930; built: 2020-09-30T16:16:37.804Z; git: de97d26f7bd222a0e16831e353d702a7a422f711; jvm 11.0.6+10
[junit4] 2> 229665 INFO (jetty-launcher-1728-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 229665 INFO (jetty-launcher-1728-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 229665 INFO (jetty-launcher-1728-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 229665 INFO (jetty-launcher-1728-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 229665 INFO (jetty-launcher-1728-thread-1) [ ] o.e.j.s.Server jetty-9.4.32.v20200930; built: 2020-09-30T16:16:37.804Z; git: de97d26f7bd222a0e16831e353d702a7a422f711; jvm 11.0.6+10
[junit4] 2> 229665 INFO (jetty-launcher-1728-thread-3) [ ] o.e.j.s.Server jetty-9.4.32.v20200930; built: 2020-09-30T16:16:37.804Z; git: de97d26f7bd222a0e16831e353d702a7a422f711; jvm 11.0.6+10
[junit4] 2> 229668 INFO (jetty-launcher-1728-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 229668 INFO (jetty-launcher-1728-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 229668 INFO (jetty-launcher-1728-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 229669 INFO (jetty-launcher-1728-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 229669 INFO (jetty-launcher-1728-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 229669 INFO (jetty-launcher-1728-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 229670 INFO (jetty-launcher-1728-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2c584dc0{/solr,null,AVAILABLE}
[junit4] 2> 229671 INFO (jetty-launcher-1728-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2e87ffef{/solr,null,AVAILABLE}
[junit4] 2> 229671 INFO (jetty-launcher-1728-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 229671 INFO (jetty-launcher-1728-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 229671 INFO (jetty-launcher-1728-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 229671 INFO (jetty-launcher-1728-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 229671 INFO (jetty-launcher-1728-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 229671 INFO (jetty-launcher-1728-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 229671 INFO (jetty-launcher-1728-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@22cd98d6{/solr,null,AVAILABLE}
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@22ca7d63{/solr,null,AVAILABLE}
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4c3fbc9f{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:45771}
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@c95be4c{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:46835}
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-1) [ ] o.e.j.s.Server Started @229939ms
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-3) [ ] o.e.j.s.Server Started @229939ms
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46835}
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45771}
[junit4] 2> 229672 ERROR (jetty-launcher-1728-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 229672 ERROR (jetty-launcher-1728-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7e6ed839{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:36239}
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-2) [ ] o.e.j.s.Server Started @229940ms
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=36239}
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-31T13:26:55.256745Z
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-31T13:26:55.256763Z
[junit4] 2> 229672 ERROR (jetty-launcher-1728-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 229672 INFO (jetty-launcher-1728-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 229673 INFO (jetty-launcher-1728-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-31T13:26:55.257005Z
[junit4] 2> 229673 INFO (jetty-launcher-1728-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 229675 INFO (jetty-launcher-1728-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 229675 INFO (jetty-launcher-1728-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@78b6a454{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:46859}
[junit4] 2> 229675 INFO (jetty-launcher-1728-thread-4) [ ] o.e.j.s.Server Started @229943ms
[junit4] 2> 229675 INFO (jetty-launcher-1728-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46859}
[junit4] 2> 229675 ERROR (jetty-launcher-1728-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 229675 INFO (jetty-launcher-1728-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 229676 INFO (jetty-launcher-1728-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 229676 INFO (jetty-launcher-1728-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 229676 INFO (jetty-launcher-1728-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 229676 INFO (jetty-launcher-1728-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 229676 INFO (jetty-launcher-1728-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-31T13:26:55.260051Z
[junit4] 2> 229677 INFO (zkConnectionManagerCallback-1734-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 229677 INFO (jetty-launcher-1728-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 229677 INFO (zkConnectionManagerCallback-1730-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 229677 INFO (jetty-launcher-1728-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 229679 INFO (zkConnectionManagerCallback-1732-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 229679 INFO (jetty-launcher-1728-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 229679 INFO (jetty-launcher-1728-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 229679 INFO (jetty-launcher-1728-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 229680 INFO (jetty-launcher-1728-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 229679 INFO (jetty-launcher-1728-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 229680 INFO (zkConnectionManagerCallback-1736-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 229680 INFO (jetty-launcher-1728-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 229680 INFO (jetty-launcher-1728-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 229683 INFO (jetty-launcher-1728-thread-2) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 229683 INFO (jetty-launcher-1728-thread-4) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 229683 INFO (jetty-launcher-1728-thread-3) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 229683 INFO (jetty-launcher-1728-thread-1) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 229902 INFO (jetty-launcher-1728-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 229904 WARN (jetty-launcher-1728-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@30dc07d9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 229904 WARN (jetty-launcher-1728-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@30dc07d9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 229906 WARN (jetty-launcher-1728-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4c0f4b35[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 229906 WARN (jetty-launcher-1728-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4c0f4b35[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 229907 INFO (jetty-launcher-1728-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45921/solr
[junit4] 2> 229908 INFO (jetty-launcher-1728-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 229909 INFO (zkConnectionManagerCallback-1750-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 229909 INFO (jetty-launcher-1728-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 229936 INFO (jetty-launcher-1728-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 229937 WARN (jetty-launcher-1728-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@14b481a5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 229937 WARN (jetty-launcher-1728-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@14b481a5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 229938 WARN (jetty-launcher-1728-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@311db9ee[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 229938 WARN (jetty-launcher-1728-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@311db9ee[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 229939 INFO (jetty-launcher-1728-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45921/solr
[junit4] 2> 229940 INFO (jetty-launcher-1728-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 229940 INFO (zkConnectionManagerCallback-1758-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 229941 INFO (jetty-launcher-1728-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 230012 INFO (jetty-launcher-1728-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 230013 WARN (jetty-launcher-1728-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5249532f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 230013 WARN (jetty-launcher-1728-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5249532f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 230014 WARN (jetty-launcher-1728-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6449f66d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 230015 WARN (jetty-launcher-1728-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6449f66d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 230015 INFO (jetty-launcher-1728-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45921/solr
[junit4] 2> 230016 INFO (jetty-launcher-1728-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 230017 INFO (zkConnectionManagerCallback-1769-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 230017 INFO (jetty-launcher-1728-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 230176 INFO (jetty-launcher-1728-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 230177 WARN (jetty-launcher-1728-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@24c3e4c7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 230177 WARN (jetty-launcher-1728-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@24c3e4c7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 230178 WARN (jetty-launcher-1728-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@47ae4132[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 230178 WARN (jetty-launcher-1728-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@47ae4132[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 230179 INFO (jetty-launcher-1728-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45921/solr
[junit4] 2> 230180 INFO (jetty-launcher-1728-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 230181 INFO (zkConnectionManagerCallback-1780-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 230181 INFO (jetty-launcher-1728-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 230185 INFO (jetty-launcher-1728-thread-3) [n:127.0.0.1:45771_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 230186 INFO (zkConnectionManagerCallback-1782-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 230186 INFO (jetty-launcher-1728-thread-3) [n:127.0.0.1:45771_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 230221 INFO (jetty-launcher-1728-thread-3) [n:127.0.0.1:45771_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:45771_solr
[junit4] 2> 230222 INFO (jetty-launcher-1728-thread-3) [n:127.0.0.1:45771_solr ] o.a.s.c.Overseer Overseer (id=72096719768846347-127.0.0.1:45771_solr-n_0000000000) starting
[junit4] 2> 230224 INFO (OverseerStateUpdate-72096719768846347-127.0.0.1:45771_solr-n_0000000000) [n:127.0.0.1:45771_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:45771_solr
[junit4] 2> 230224 INFO (jetty-launcher-1728-thread-3) [n:127.0.0.1:45771_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45771_solr
[junit4] 2> 230225 INFO (OverseerStateUpdate-72096719768846347-127.0.0.1:45771_solr-n_0000000000) [n:127.0.0.1:45771_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 230225 INFO (jetty-launcher-1728-thread-3) [n:127.0.0.1:45771_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 230225 WARN (jetty-launcher-1728-thread-3) [n:127.0.0.1:45771_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 230234 INFO (jetty-launcher-1728-thread-3) [n:127.0.0.1:45771_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_8DF3F9FC89E1D363-001/tempDir-001/node3
[junit4] 2> 230285 INFO (jetty-launcher-1728-thread-1) [n:127.0.0.1:46835_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 230286 INFO (zkConnectionManagerCallback-1794-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 230286 INFO (jetty-launcher-1728-thread-1) [n:127.0.0.1:46835_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 230288 INFO (jetty-launcher-1728-thread-1) [n:127.0.0.1:46835_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 230290 INFO (jetty-launcher-1728-thread-1) [n:127.0.0.1:46835_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:46835_solr as DOWN
[junit4] 2> 230290 INFO (jetty-launcher-1728-thread-1) [n:127.0.0.1:46835_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 230290 INFO (jetty-launcher-1728-thread-1) [n:127.0.0.1:46835_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46835_solr
[junit4] 2> 230290 INFO (zkCallback-1781-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 230291 INFO (zkCallback-1793-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 230291 INFO (jetty-launcher-1728-thread-1) [n:127.0.0.1:46835_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 230291 WARN (jetty-launcher-1728-thread-1) [n:127.0.0.1:46835_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 230300 INFO (jetty-launcher-1728-thread-1) [n:127.0.0.1:46835_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_8DF3F9FC89E1D363-001/tempDir-001/node1
[junit4] 2> 230386 INFO (jetty-launcher-1728-thread-4) [n:127.0.0.1:46859_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 230387 INFO (zkConnectionManagerCallback-1802-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 230387 INFO (jetty-launcher-1728-thread-4) [n:127.0.0.1:46859_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 230389 INFO (jetty-launcher-1728-thread-4) [n:127.0.0.1:46859_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 230391 INFO (jetty-launcher-1728-thread-4) [n:127.0.0.1:46859_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:46859_solr as DOWN
[junit4] 2> 230392 INFO (jetty-launcher-1728-thread-4) [n:127.0.0.1:46859_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 230392 INFO (jetty-launcher-1728-thread-4) [n:127.0.0.1:46859_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46859_solr
[junit4] 2> 230392 INFO (zkCallback-1781-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 230392 INFO (zkCallback-1793-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 230393 INFO (zkCallback-1801-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 230396 INFO (jetty-launcher-1728-thread-4) [n:127.0.0.1:46859_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 230396 WARN (jetty-launcher-1728-thread-4) [n:127.0.0.1:46859_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 230405 INFO (jetty-launcher-1728-thread-4) [n:127.0.0.1:46859_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_8DF3F9FC89E1D363-001/tempDir-001/node4
[junit4] 2> 230486 INFO (jetty-launcher-1728-thread-2) [n:127.0.0.1:36239_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 230488 INFO (zkConnectionManagerCallback-1810-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 230488 INFO (jetty-launcher-1728-thread-2) [n:127.0.0.1:36239_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 230490 INFO (jetty-launcher-1728-thread-2) [n:127.0.0.1:36239_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 230492 INFO (jetty-launcher-1728-thread-2) [n:127.0.0.1:36239_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:36239_solr as DOWN
[junit4] 2> 230493 INFO (jetty-launcher-1728-thread-2) [n:127.0.0.1:36239_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 230493 INFO (jetty-launcher-1728-thread-2) [n:127.0.0.1:36239_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36239_solr
[junit4] 2> 230494 INFO (zkCallback-1781-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 230494 INFO (zkCallback-1793-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 230494 INFO (zkCallback-1801-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 230494 INFO (zkCallback-1809-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 230495 INFO (jetty-launcher-1728-thread-2) [n:127.0.0.1:36239_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 230495 WARN (jetty-launcher-1728-thread-2) [n:127.0.0.1:36239_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 230504 INFO (jetty-launcher-1728-thread-2) [n:127.0.0.1:36239_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_8DF3F9FC89E1D363-001/tempDir-001/node2
[junit4] 2> 230510 INFO (TEST-TestPackages.testPluginLoading-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=4
[junit4] 2> 230510 INFO (TEST-TestPackages.testPluginLoading-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 230512 INFO (zkConnectionManagerCallback-1821-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 230512 INFO (TEST-TestPackages.testPluginLoading-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 230512 INFO (TEST-TestPackages.testPluginLoading-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 230513 INFO (TEST-TestPackages.testPluginLoading-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:45921/solr ready
[junit4] 2> 230551 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={getFrom=127.0.0.1:45771_solr} status=0 QTime=0
[junit4] 2> 230552 INFO (updateExecutor-1754-thread-1-processing-n:127.0.0.1:46835_solr) [n:127.0.0.1:46835_solr ] o.a.s.f.DistribPackageStore fetching a file /_trusted_/keys/pub_key512.der from 127.0.0.1:45771_solr
[junit4] 2> 230561 INFO (qtp1187502063-3334) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/.pub_key512.der.json params={} status=0 QTime=0
[junit4] 2> 230562 INFO (qtp1187502063-3341) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={} status=0 QTime=0
[junit4] 2> 230564 INFO (updateExecutor-1754-thread-1-processing-n:127.0.0.1:46835_solr) [n:127.0.0.1:46835_solr ] o.a.s.f.DistribPackageStore persisted a file /_trusted_/keys/pub_key512.der and metadata. sizes 94 141
[junit4] 2> 230564 INFO (updateExecutor-1754-thread-1-processing-n:127.0.0.1:46835_solr) [n:127.0.0.1:46835_solr ] o.a.s.f.PackageStoreAPI downloaded file: /_trusted_/keys/pub_key512.der
[junit4] 2> 230565 INFO (qtp1956033353-3339) [n:127.0.0.1:36239_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={getFrom=127.0.0.1:45771_solr} status=0 QTime=0
[junit4] 2> 230565 INFO (updateExecutor-1776-thread-1-processing-n:127.0.0.1:36239_solr) [n:127.0.0.1:36239_solr ] o.a.s.f.DistribPackageStore fetching a file /_trusted_/keys/pub_key512.der from 127.0.0.1:45771_solr
[junit4] 2> 230576 INFO (qtp1187502063-3334) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/.pub_key512.der.json params={} status=0 QTime=0
[junit4] 2> 230577 INFO (qtp1187502063-3341) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={} status=0 QTime=0
[junit4] 2> 230579 INFO (updateExecutor-1776-thread-1-processing-n:127.0.0.1:36239_solr) [n:127.0.0.1:36239_solr ] o.a.s.f.DistribPackageStore persisted a file /_trusted_/keys/pub_key512.der and metadata. sizes 94 141
[junit4] 2> 230580 INFO (updateExecutor-1776-thread-1-processing-n:127.0.0.1:36239_solr) [n:127.0.0.1:36239_solr ] o.a.s.f.PackageStoreAPI downloaded file: /_trusted_/keys/pub_key512.der
[junit4] 2> 230587 INFO (qtp373952921-3344) [n:127.0.0.1:46859_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={getFrom=127.0.0.1:45771_solr} status=0 QTime=3
[junit4] 2> 230588 INFO (updateExecutor-1746-thread-1-processing-n:127.0.0.1:46859_solr) [n:127.0.0.1:46859_solr ] o.a.s.f.DistribPackageStore fetching a file /_trusted_/keys/pub_key512.der from 127.0.0.1:45771_solr
[junit4] 2> 230589 INFO (qtp1187502063-3332) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={sync=true} status=0 QTime=54
[junit4] 1> sync resp: https://127.0.0.1:45771/api/node/files/_trusted_/keys/pub_key512.der?sync=true ,is: null
[junit4] 2> 230597 INFO (qtp1187502063-3341) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/.pub_key512.der.json params={} status=0 QTime=0
[junit4] 2> 230598 INFO (qtp1187502063-3334) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={} status=0 QTime=0
[junit4] 2> 230599 INFO (updateExecutor-1746-thread-1-processing-n:127.0.0.1:46859_solr) [n:127.0.0.1:46859_solr ] o.a.s.f.DistribPackageStore persisted a file /_trusted_/keys/pub_key512.der and metadata. sizes 94 141
[junit4] 2> 230599 INFO (updateExecutor-1746-thread-1-processing-n:127.0.0.1:46859_solr) [n:127.0.0.1:46859_solr ] o.a.s.f.PackageStoreAPI downloaded file: /_trusted_/keys/pub_key512.der
[junit4] 2> 230604 INFO (qtp1187502063-3334) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={meta=true&wt=javabin} status=0 QTime=1
[junit4] 2> 230618 INFO (qtp107861308-3337) [n:127.0.0.1:46835_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 230633 INFO (qtp373952921-3346) [n:127.0.0.1:46859_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 230645 INFO (qtp1956033353-3342) [n:127.0.0.1:36239_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 230673 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 230675 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.f.DistribPackageStore Missing file in package store: /mypkg/runtimelibs.jar
[junit4] 2> 230688 INFO (qtp373952921-3346) [n:127.0.0.1:46859_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 230701 INFO (qtp1956033353-3342) [n:127.0.0.1:36239_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 230702 INFO (qtp1187502063-3334) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 230702 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.f.DistribPackageStore Unable to download file : /mypkg/runtimelibs.jar
[junit4] 2> 230704 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/runtimelibs.jar and metadata. sizes 6860 246
[junit4] 2> 230707 INFO (qtp1187502063-3341) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={getFrom=127.0.0.1:46835_solr} status=0 QTime=1
[junit4] 2> 230708 INFO (updateExecutor-1765-thread-2-processing-n:127.0.0.1:45771_solr) [n:127.0.0.1:45771_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/runtimelibs.jar from 127.0.0.1:46835_solr
[junit4] 2> 230709 INFO (qtp107861308-3337) [n:127.0.0.1:46835_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.runtimelibs.jar.json params={} status=0 QTime=0
[junit4] 2> 230710 INFO (qtp107861308-3337) [n:127.0.0.1:46835_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={} status=0 QTime=0
[junit4] 2> 230710 INFO (qtp1956033353-3339) [n:127.0.0.1:36239_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={getFrom=127.0.0.1:46835_solr} status=0 QTime=0
[junit4] 2> 230710 INFO (updateExecutor-1776-thread-1-processing-n:127.0.0.1:36239_solr) [n:127.0.0.1:36239_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/runtimelibs.jar from 127.0.0.1:46835_solr
[junit4] 2> 230711 INFO (qtp373952921-3344) [n:127.0.0.1:46859_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={getFrom=127.0.0.1:46835_solr} status=0 QTime=0
[junit4] 2> 230711 INFO (updateExecutor-1746-thread-1-processing-n:127.0.0.1:46859_solr) [n:127.0.0.1:46859_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/runtimelibs.jar from 127.0.0.1:46835_solr
[junit4] 2> 230713 INFO (updateExecutor-1765-thread-2-processing-n:127.0.0.1:45771_solr) [n:127.0.0.1:45771_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/runtimelibs.jar and metadata. sizes 6860 246
[junit4] 2> 230713 INFO (updateExecutor-1765-thread-2-processing-n:127.0.0.1:45771_solr) [n:127.0.0.1:45771_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/runtimelibs.jar
[junit4] 2> 230713 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/files/mypkg/runtimelibs.jar params={sig=L3q/qIGs4NaF6JiO0ZkMUFa88j0OmYc%2BI6O7BOdNuMct/xoZ4h73aZHZGc0%2BnmI1f/U3bOlMPINlSOM6LK3JpQ%3D%3D&wt=javabin&version=2} status=0 QTime=49
[junit4] 2> 230719 INFO (qtp107861308-3354) [n:127.0.0.1:46835_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.runtimelibs.jar.json params={} status=0 QTime=0
[junit4] 2> 230721 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={} status=0 QTime=0
[junit4] 2> 230723 INFO (updateExecutor-1776-thread-1-processing-n:127.0.0.1:36239_solr) [n:127.0.0.1:36239_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/runtimelibs.jar and metadata. sizes 6860 246
[junit4] 2> 230723 INFO (updateExecutor-1776-thread-1-processing-n:127.0.0.1:36239_solr) [n:127.0.0.1:36239_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/runtimelibs.jar
[junit4] 2> 230724 INFO (qtp107861308-3337) [n:127.0.0.1:46835_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.runtimelibs.jar.json params={} status=0 QTime=0
[junit4] 2> 230726 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={} status=0 QTime=0
[junit4] 2> 230727 INFO (qtp1187502063-3332) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 230728 INFO (updateExecutor-1746-thread-1-processing-n:127.0.0.1:46859_solr) [n:127.0.0.1:46859_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/runtimelibs.jar and metadata. sizes 6860 246
[junit4] 2> 230728 INFO (updateExecutor-1746-thread-1-processing-n:127.0.0.1:46859_solr) [n:127.0.0.1:46859_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/runtimelibs.jar
[junit4] 2> 230740 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 230751 INFO (qtp373952921-3346) [n:127.0.0.1:46859_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 230763 INFO (qtp1956033353-3342) [n:127.0.0.1:36239_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/runtimelibs.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 230778 INFO (qtp1187502063-3332) [n:127.0.0.1:45771_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 230779 INFO (qtp1187502063-3332) [n:127.0.0.1:45771_solr ] o.a.s.f.DistribPackageStore Missing file in package store: /mypkg/testurpv1.jar
[junit4] 2> 230780 INFO (qtp373952921-3346) [n:127.0.0.1:46859_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 230781 INFO (qtp107861308-3354) [n:127.0.0.1:46835_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 230782 INFO (qtp1956033353-3342) [n:127.0.0.1:36239_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 230782 INFO (qtp1187502063-3332) [n:127.0.0.1:45771_solr ] o.a.s.f.DistribPackageStore Unable to download file : /mypkg/testurpv1.jar
[junit4] 2> 230783 INFO (qtp1187502063-3332) [n:127.0.0.1:45771_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/testurpv1.jar and metadata. sizes 689 246
[junit4] 2> 230789 INFO (qtp107861308-3337) [n:127.0.0.1:46835_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={getFrom=127.0.0.1:45771_solr} status=0 QTime=0
[junit4] 2> 230790 INFO (updateExecutor-1754-thread-2-processing-n:127.0.0.1:46835_solr) [n:127.0.0.1:46835_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/testurpv1.jar from 127.0.0.1:45771_solr
[junit4] 2> 230790 INFO (qtp1956033353-3339) [n:127.0.0.1:36239_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={getFrom=127.0.0.1:45771_solr} status=0 QTime=0
[junit4] 2> 230791 INFO (updateExecutor-1776-thread-1-processing-n:127.0.0.1:36239_solr) [n:127.0.0.1:36239_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/testurpv1.jar from 127.0.0.1:45771_solr
[junit4] 2> 230791 INFO (qtp1187502063-3341) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.testurpv1.jar.json params={} status=0 QTime=0
[junit4] 2> 230791 INFO (qtp373952921-3344) [n:127.0.0.1:46859_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={getFrom=127.0.0.1:45771_solr} status=0 QTime=0
[junit4] 2> 230791 INFO (qtp1187502063-3334) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.testurpv1.jar.json params={} status=0 QTime=0
[junit4] 2> 230791 INFO (updateExecutor-1746-thread-1-processing-n:127.0.0.1:46859_solr) [n:127.0.0.1:46859_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/testurpv1.jar from 127.0.0.1:45771_solr
[junit4] 2> 230791 INFO (qtp1187502063-3341) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={} status=0 QTime=0
[junit4] 2> 230792 INFO (qtp1187502063-3334) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.testurpv1.jar.json params={} status=0 QTime=0
[junit4] 2> 230792 INFO (qtp1187502063-3332) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/files/mypkg/testurpv1.jar params={sig=h6UmMzuPqu4hQFGLBMJh/6kDSEXpJlgLsQDXx0KuxXWkV5giilRP57K3towiJRh2J%2BrqihqIghNCi3YgzgUnWQ%3D%3D&wt=javabin&version=2} status=0 QTime=15
[junit4] 2> 230792 INFO (qtp1187502063-3360) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={} status=0 QTime=0
[junit4] 2> 230793 INFO (qtp1187502063-3341) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={} status=0 QTime=0
[junit4] 2> 230793 INFO (updateExecutor-1754-thread-2-processing-n:127.0.0.1:46835_solr) [n:127.0.0.1:46835_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/testurpv1.jar and metadata. sizes 689 246
[junit4] 2> 230793 INFO (updateExecutor-1754-thread-2-processing-n:127.0.0.1:46835_solr) [n:127.0.0.1:46835_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/testurpv1.jar
[junit4] 2> 230794 INFO (updateExecutor-1746-thread-1-processing-n:127.0.0.1:46859_solr) [n:127.0.0.1:46859_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/testurpv1.jar and metadata. sizes 689 246
[junit4] 2> 230794 INFO (updateExecutor-1776-thread-1-processing-n:127.0.0.1:36239_solr) [n:127.0.0.1:36239_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/testurpv1.jar and metadata. sizes 689 246
[junit4] 2> 230794 INFO (updateExecutor-1746-thread-1-processing-n:127.0.0.1:46859_solr) [n:127.0.0.1:46859_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/testurpv1.jar
[junit4] 2> 230794 INFO (updateExecutor-1776-thread-1-processing-n:127.0.0.1:36239_solr) [n:127.0.0.1:36239_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/testurpv1.jar
[junit4] 2> 230820 INFO (qtp1187502063-3360) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={meta=true&wt=javabin} status=0 QTime=1
[junit4] 2> 230834 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 230851 INFO (qtp373952921-3346) [n:127.0.0.1:46859_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 230873 INFO (qtp1956033353-3339) [n:127.0.0.1:36239_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/testurpv1.jar params={meta=true&wt=javabin} status=0 QTime=1
[junit4] 2> 230888 INFO (qtp1187502063-3360) [n:127.0.0.1:45771_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 230889 INFO (qtp1187502063-3360) [n:127.0.0.1:45771_solr ] o.a.s.f.DistribPackageStore Missing file in package store: /mypkg/expressible.jar
[junit4] 2> 230891 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 230893 INFO (qtp373952921-3346) [n:127.0.0.1:46859_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 230894 INFO (qtp1956033353-3339) [n:127.0.0.1:36239_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 230895 INFO (qtp1187502063-3360) [n:127.0.0.1:45771_solr ] o.a.s.f.DistribPackageStore Unable to download file : /mypkg/expressible.jar
[junit4] 2> 230895 INFO (qtp1187502063-3360) [n:127.0.0.1:45771_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/expressible.jar and metadata. sizes 1977 246
[junit4] 2> 230898 INFO (qtp373952921-3344) [n:127.0.0.1:46859_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={getFrom=127.0.0.1:45771_solr} status=0 QTime=0
[junit4] 2> 230898 INFO (updateExecutor-1746-thread-1-processing-n:127.0.0.1:46859_solr) [n:127.0.0.1:46859_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/expressible.jar from 127.0.0.1:45771_solr
[junit4] 2> 230899 INFO (qtp1956033353-3342) [n:127.0.0.1:36239_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={getFrom=127.0.0.1:45771_solr} status=0 QTime=0
[junit4] 2> 230899 INFO (updateExecutor-1776-thread-1-processing-n:127.0.0.1:36239_solr) [n:127.0.0.1:36239_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/expressible.jar from 127.0.0.1:45771_solr
[junit4] 2> 230899 INFO (qtp1187502063-3341) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.expressible.jar.json params={} status=0 QTime=0
[junit4] 2> 230899 INFO (qtp107861308-3354) [n:127.0.0.1:46835_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={getFrom=127.0.0.1:45771_solr} status=0 QTime=0
[junit4] 2> 230900 INFO (updateExecutor-1754-thread-2-processing-n:127.0.0.1:46835_solr) [n:127.0.0.1:46835_solr ] o.a.s.f.DistribPackageStore fetching a file /mypkg/expressible.jar from 127.0.0.1:45771_solr
[junit4] 2> 230900 INFO (qtp1187502063-3341) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.expressible.jar.json params={} status=0 QTime=0
[junit4] 2> 230901 INFO (qtp1187502063-3341) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/.expressible.jar.json params={} status=0 QTime=0
[junit4] 2> 230901 INFO (qtp1187502063-3341) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={} status=0 QTime=0
[junit4] 2> 230902 INFO (qtp1187502063-3341) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={} status=0 QTime=0
[junit4] 2> 230903 INFO (updateExecutor-1746-thread-1-processing-n:127.0.0.1:46859_solr) [n:127.0.0.1:46859_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/expressible.jar and metadata. sizes 1977 246
[junit4] 2> 230903 INFO (qtp1187502063-3341) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={} status=0 QTime=0
[junit4] 2> 230903 INFO (updateExecutor-1746-thread-1-processing-n:127.0.0.1:46859_solr) [n:127.0.0.1:46859_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/expressible.jar
[junit4] 2> 230903 INFO (updateExecutor-1776-thread-1-processing-n:127.0.0.1:36239_solr) [n:127.0.0.1:36239_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/expressible.jar and metadata. sizes 1977 246
[junit4] 2> 230903 INFO (updateExecutor-1776-thread-1-processing-n:127.0.0.1:36239_solr) [n:127.0.0.1:36239_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/expressible.jar
[junit4] 2> 230904 INFO (updateExecutor-1754-thread-2-processing-n:127.0.0.1:46835_solr) [n:127.0.0.1:46835_solr ] o.a.s.f.DistribPackageStore persisted a file /mypkg/expressible.jar and metadata. sizes 1977 246
[junit4] 2> 230904 INFO (updateExecutor-1754-thread-2-processing-n:127.0.0.1:46835_solr) [n:127.0.0.1:46835_solr ] o.a.s.f.PackageStoreAPI downloaded file: /mypkg/expressible.jar
[junit4] 2> 230905 INFO (qtp1187502063-3360) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/files/mypkg/expressible.jar params={sig=ZOT11arAiPmPZYOHzqodiNnxO9pRyRozWZEBX8XGjU1/HJptFnZK%2BDI7eXnUtbNaMcbXE2Ze8hh4M/eGyhY8BQ%3D%3D&wt=javabin&version=2} status=0 QTime=28
[junit4] 2> 230918 INFO (qtp1187502063-3341) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 230930 INFO (qtp107861308-3337) [n:127.0.0.1:46835_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 230944 INFO (qtp373952921-3344) [n:127.0.0.1:46859_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={meta=true&wt=javabin} status=0 QTime=0
[junit4] 2> 230958 INFO (qtp1956033353-3342) [n:127.0.0.1:36239_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/mypkg/expressible.jar params={meta=true&wt=javabin} status=0 QTime=1
[junit4] 2> 230998 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 230999 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231001 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231002 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231004 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231005 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231009 DEBUG (zkCallback-1793-thread-1) [ ] o.a.s.p.PackageAPI Updating [/packages.json] ...
[junit4] 2> 231009 DEBUG (zkCallback-1809-thread-1) [ ] o.a.s.p.PackageAPI Updating [/packages.json] ...
[junit4] 2> 231010 INFO (qtp1187502063-3360) [n:127.0.0.1:45771_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={expectedVersion1=&omitHeader=true&wt=javabin} status=0 QTime=0
[junit4] 2> 231011 INFO (qtp1956033353-3339) [n:127.0.0.1:36239_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={expectedVersion1=&omitHeader=true&wt=javabin} status=0 QTime=0
[junit4] 2> 231011 INFO (qtp373952921-3344) [n:127.0.0.1:46859_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={expectedVersion1=&omitHeader=true&wt=javabin} status=0 QTime=0
[junit4] 2> 231011 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.p.PackageLoader /packages.json updated to version 1
[junit4] 2> 231012 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.p.PackageLoader A new package: mypkg introduced
[junit4] 2> 231012 DEBUG (zkCallback-1781-thread-1) [ ] o.a.s.p.PackageAPI Updating [/packages.json] ...
[junit4] 2> 231012 DEBUG (zkCallback-1801-thread-1) [ ] o.a.s.p.PackageAPI Updating [/packages.json] ...
[junit4] 2> 231013 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.p.PackageLoader A new version: 1.0 added for package: mypkg with artifacts [/mypkg/runtimelibs.jar, /mypkg/testurpv1.jar, /mypkg/expressible.jar]
[junit4] 2> 231022 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231024 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231025 INFO (zkCallback-1781-thread-1) [ ] o.a.s.p.PackageLoader /packages.json updated to version 0
[junit4] 2> 231025 INFO (zkCallback-1801-thread-1) [ ] o.a.s.p.PackageLoader /packages.json updated to version 0
[junit4] 2> 231025 INFO (zkCallback-1781-thread-1) [ ] o.a.s.p.PackageLoader A new package: mypkg introduced
[junit4] 2> 231025 INFO (zkCallback-1801-thread-1) [ ] o.a.s.p.PackageLoader A new package: mypkg introduced
[junit4] 2> 231025 INFO (zkCallback-1801-thread-1) [ ] o.a.s.p.PackageLoader A new version: 1.0 added for package: mypkg with artifacts [/mypkg/runtimelibs.jar, /mypkg/testurpv1.jar, /mypkg/expressible.jar]
[junit4] 2> 231025 INFO (zkCallback-1781-thread-1) [ ] o.a.s.p.PackageLoader A new version: 1.0 added for package: mypkg with artifacts [/mypkg/runtimelibs.jar, /mypkg/testurpv1.jar, /mypkg/expressible.jar]
[junit4] 2> 231025 INFO (zkCallback-1793-thread-1) [ ] o.a.s.p.PackageLoader /packages.json updated to version 0
[junit4] 2> 231025 INFO (zkCallback-1793-thread-1) [ ] o.a.s.p.PackageLoader A new package: mypkg introduced
[junit4] 2> 231025 INFO (zkCallback-1809-thread-1) [ ] o.a.s.p.PackageLoader /packages.json updated to version 0
[junit4] 2> 231025 INFO (zkCallback-1809-thread-1) [ ] o.a.s.p.PackageLoader A new package: mypkg introduced
[junit4] 2> 231026 INFO (zkCallback-1809-thread-1) [ ] o.a.s.p.PackageLoader A new version: 1.0 added for package: mypkg with artifacts [/mypkg/runtimelibs.jar, /mypkg/testurpv1.jar, /mypkg/expressible.jar]
[junit4] 2> 231026 INFO (zkCallback-1781-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231026 INFO (zkCallback-1801-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231026 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231027 INFO (zkCallback-1809-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231027 INFO (zkCallback-1781-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231027 INFO (zkCallback-1801-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231030 INFO (zkCallback-1801-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231030 INFO (zkCallback-1781-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231030 INFO (zkCallback-1809-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231031 INFO (zkCallback-1801-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231032 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231032 INFO (zkCallback-1809-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231033 INFO (zkCallback-1781-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231033 INFO (zkCallback-1801-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231033 INFO (zkCallback-1809-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231034 INFO (zkCallback-1801-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231035 INFO (zkCallback-1809-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231035 INFO (zkCallback-1781-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231036 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231036 INFO (zkCallback-1781-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231036 INFO (zkCallback-1809-thread-1) [ ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231036 INFO (zkCallback-1801-thread-1) [ ] o.a.s.c.SolrResourceLoader Added 3 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_8DF3F9FC89E1D363-001/tempDir-001/node4/filestore/mypkg]
[junit4] 2> 231036 INFO (zkCallback-1801-thread-1) [ ] o.a.s.p.PackageLoader version: 1.0 is the new latest in package: mypkg
[junit4] 2> 231037 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading..
[junit4] 2> 231037 INFO (zkCallback-1809-thread-1) [ ] o.a.s.c.SolrResourceLoader Added 3 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_8DF3F9FC89E1D363-001/tempDir-001/node2/filestore/mypkg]
[junit4] 2> 231037 INFO (zkCallback-1809-thread-1) [ ] o.a.s.p.PackageLoader version: 1.0 is the new latest in package: mypkg
[junit4] 2> 231037 INFO (zkCallback-1781-thread-1) [ ] o.a.s.c.SolrResourceLoader Added 3 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_8DF3F9FC89E1D363-001/tempDir-001/node3/filestore/mypkg]
[junit4] 2> 231037 INFO (zkCallback-1781-thread-1) [ ] o.a.s.p.PackageLoader version: 1.0 is the new latest in package: mypkg
[junit4] 2> 231038 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.c.SolrResourceLoader Added 3 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_8DF3F9FC89E1D363-001/tempDir-001/node1/filestore/mypkg]
[junit4] 2> 231038 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.p.PackageLoader version: 1.0 is the new latest in package: mypkg
[junit4] 2> 231038 INFO (qtp107861308-3335) [n:127.0.0.1:46835_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={wt=javabin&version=2} status=0 QTime=74
[junit4] 2> 231049 INFO (qtp1956033353-3339) [n:127.0.0.1:36239_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&maxShardsPerNode=100&name=testPluginLoadingColl&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 231057 INFO (OverseerThreadFactory-1789-thread-1-processing-n:127.0.0.1:45771_solr) [n:127.0.0.1:45771_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testPluginLoadingColl
[junit4] 2> 231162 INFO (OverseerStateUpdate-72096719768846347-127.0.0.1:45771_solr-n_0000000000) [n:127.0.0.1:45771_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testPluginLoadingColl",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testPluginLoadingColl_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:46835/solr",
[junit4] 2> "node_name":"127.0.0.1:46835_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 231163 INFO (OverseerStateUpdate-72096719768846347-127.0.0.1:45771_solr-n_0000000000) [n:127.0.0.1:45771_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testPluginLoadingColl",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testPluginLoadingColl_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:46859/solr",
[junit4] 2> "node_name":"127.0.0.1:46859_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 231164 INFO (OverseerStateUpdate-72096719768846347-127.0.0.1:45771_solr-n_0000000000) [n:127.0.0.1:45771_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testPluginLoadingColl",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"testPluginLoadingColl_shard2_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:36239/solr",
[junit4] 2> "node_name":"127.0.0.1:36239_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 231165 INFO (OverseerStateUpdate-72096719768846347-127.0.0.1:45771_solr-n_0000000000) [n:127.0.0.1:45771_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testPluginLoadingColl",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"testPluginLoadingColl_shard2_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:45771/solr",
[junit4] 2> "node_name":"127.0.0.1:45771_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 231368 INFO (qtp1956033353-3342) [n:127.0.0.1:36239_solr x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=testPluginLoadingColl_shard2_replica_n4&action=CREATE&numShards=2&collection=testPluginLoadingColl&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 231368 INFO (qtp107861308-3354) [n:127.0.0.1:46835_solr x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=testPluginLoadingColl_shard1_replica_n1&action=CREATE&numShards=2&collection=testPluginLoadingColl&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 231368 INFO (qtp373952921-3346) [n:127.0.0.1:46859_solr x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=testPluginLoadingColl_shard1_replica_n2&action=CREATE&numShards=2&collection=testPluginLoadingColl&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 231374 INFO (qtp107861308-3354) [n:127.0.0.1:46835_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0
[junit4] 2> 231374 INFO (qtp373952921-3346) [n:127.0.0.1:46859_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0
[junit4] 2> 231374 INFO (qtp1956033353-3342) [n:127.0.0.1:36239_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0
[junit4] 2> 231380 INFO (qtp1187502063-3360) [n:127.0.0.1:45771_solr x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=testPluginLoadingColl_shard2_replica_n6&action=CREATE&numShards=2&collection=testPluginLoadingColl&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 231381 INFO (qtp1187502063-3360) [n:127.0.0.1:45771_solr x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 231385 INFO (qtp1956033353-3342) [n:127.0.0.1:36239_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 231385 INFO (qtp373952921-3346) [n:127.0.0.1:46859_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 231385 INFO (qtp107861308-3354) [n:127.0.0.1:46835_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 231388 INFO (qtp1956033353-3342) [n:127.0.0.1:36239_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 231388 INFO (qtp373952921-3346) [n:127.0.0.1:46859_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 231388 INFO (qtp107861308-3354) [n:127.0.0.1:46835_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 231388 INFO (qtp1956033353-3342) [n:127.0.0.1:36239_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.c.CoreContainer Creating SolrCore 'testPluginLoadingColl_shard2_replica_n4' using configuration from configset conf, trusted=true
[junit4] 2> 231388 INFO (qtp373952921-3346) [n:127.0.0.1:46859_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'testPluginLoadingColl_shard1_replica_n2' using configuration from configset conf, trusted=true
[junit4] 2> 231388 INFO (qtp107861308-3354) [n:127.0.0.1:46835_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testPluginLoadingColl_shard1_replica_n1' using configuration from configset conf, trusted=true
[junit4] 2> 231388 INFO (qtp373952921-3346) [n:127.0.0.1:46859_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.c.SolrCore [[testPluginLoadingColl_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_8DF3F9FC89E1D363-001/tempDir-001/node4/testPluginLoadingColl_shard1_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_8DF3F9FC89E1D363-001/tempDir-001/node4/testPluginLoadingColl_shard1_replica_n2/data/]
[junit4] 2> 231388 INFO (qtp107861308-3354) [n:127.0.0.1:46835_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.c.SolrCore [[testPluginLoadingColl_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_8DF3F9FC89E1D363-001/tempDir-001/node1/testPluginLoadingColl_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_8DF3F9FC89E1D363-001/tempDir-001/node1/testPluginLoadingColl_shard1_replica_n1/data/]
[junit4] 2> 231388 INFO (qtp1956033353-3342) [n:127.0.0.1:36239_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.c.SolrCore [[testPluginLoadingColl_shard2_replica_n4] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_8DF3F9FC89E1D363-001/tempDir-001/node2/testPluginLoadingColl_shard2_replica_n4], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_8DF3F9FC89E1D363-001/tempDir-001/node2/testPluginLoadingColl_shard2_replica_n4/data/]
[junit4] 2> 231389 INFO (qtp1187502063-3360) [n:127.0.0.1:45771_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0
[junit4] 2> 231417 INFO (qtp1187502063-3360) [n:127.0.0.1:45771_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 231418 INFO (qtp1187502063-3360) [n:127.0.0.1:45771_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 231418 INFO (qtp1187502063-3360) [n:127.0.0.1:45771_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.c.CoreContainer Creating SolrCore 'testPluginLoadingColl_shard2_replica_n6' using configuration from configset conf, trusted=true
[junit4] 2> 231419 INFO (qtp1187502063-3360) [n:127.0.0.1:45771_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.c.SolrCore [[testPluginLoadingColl_shard2_replica_n6] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_8DF3F9FC89E1D363-001/tempDir-001/node3/testPluginLoadingColl_shard2_replica_n6], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_8DF3F9FC89E1D363-001/tempDir-001/node3/testPluginLoadingColl_shard2_replica_n6/data/]
[junit4] 2> 231440 INFO (qtp1956033353-3342) [n:127.0.0.1:36239_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 231440 INFO (qtp1956033353-3342) [n:127.0.0.1:36239_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 231440 INFO (qtp107861308-3354) [n:127.0.0.1:46835_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 231440 INFO (qtp107861308-3354) [n:127.0.0.1:46835_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 231441 INFO (qtp373952921-3346) [n:127.0.0.1:46859_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 231441 INFO (qtp373952921-3346) [n:127.0.0.1:46859_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 231441 INFO (qtp1187502063-3360) [n:127.0.0.1:45771_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 231441 INFO (qtp1187502063-3360) [n:127.0.0.1:45771_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 231442 INFO (qtp1956033353-3342) [n:127.0.0.1:36239_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 231442 INFO (qtp1187502063-3360) [n:127.0.0.1:45771_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 231442 INFO (qtp1956033353-3342) [n:127.0.0.1:36239_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 231442 INFO (qtp1187502063-3360) [n:127.0.0.1:45771_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 231442 INFO (qtp107861308-3354) [n:127.0.0.1:46835_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 231442 INFO (qtp107861308-3354) [n:127.0.0.1:46835_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 231442 INFO (qtp373952921-3346) [n:127.0.0.1:46859_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 231443 INFO (qtp373952921-3346) [n:127.0.0.1:46859_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 231443 INFO (qtp1956033353-3342) [n:127.0.0.1:36239_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 231443 INFO (qtp1187502063-3360) [n:127.0.0.1:45771_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 231443 INFO (qtp107861308-3354) [n:127.0.0.1:46835_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 231443 INFO (qtp373952921-3346) [n:127.0.0.1:46859_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 231444 INFO (qtp1956033353-3342) [n:127.0.0.1:36239_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 231444 INFO (qtp1187502063-3360) [n:127.0.0.1:45771_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 231444 INFO (qtp373952921-3346) [n:127.0.0.1:46859_solr c:testPluginLoadingColl s:shard1 r:core_node5 x:testPluginLoadingColl_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 231444 INFO (qtp107861308-3354) [n:127.0.0.1:46835_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 231444 INFO (qtp1187502063-3360) [n:127.0.0.1:45771_solr c:testPluginLoadingColl s:shard2 r:core_node8 x:testPluginLoadingColl_shard2_replica_n6 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 231444 INFO (qtp1956033353-3342) [n:127.0.0.1:36239_solr c:testPluginLoadingColl s:shard2 r:core_node7 x:testPluginLoadingColl_shard2_replica_n4 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 231444 INFO (qtp107861308-3354) [n:127.0.0.1:46835_solr c:testPluginLoadingColl s:shard1 r:core_node3 x:testPluginLoadingColl_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 231444 INFO (qtp373952921-3346) [n:127.0.0.1:46859_solr c:testPluginLoa
[...truncated too long message...]
[junit4] 2> 255003 INFO (closeThreadPool-2160-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72096721287315467-127.0.0.1:38757_solr-n_0000000000) closing
[junit4] 2> 255011 INFO (jetty-closer-2145-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 255012 INFO (jetty-closer-2145-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 255012 INFO (jetty-closer-2145-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 255012 INFO (jetty-closer-2145-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 255015 INFO (jetty-closer-2145-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 255015 INFO (jetty-closer-2145-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 255015 INFO (jetty-closer-2145-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 255016 INFO (jetty-closer-2145-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 255101 WARN (jetty-launcher-2051-thread-1-SendThread(127.0.0.1:34469)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100239605cd000e.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100239605cd000e, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x100239605cd000e, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 255102 WARN (jetty-launcher-2051-thread-3-SendThread(127.0.0.1:34469)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100239605cd000b.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100239605cd000b, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x100239605cd000b, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 255403 INFO (jetty-closer-2145-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72096721287315467-127.0.0.1:38757_solr-n_0000000000) closing
[junit4] 2> 264294 INFO (jetty-closer-2145-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2c49cd7{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
[junit4] 2> 264294 INFO (jetty-closer-2145-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@40834dc1{/solr,null,STOPPED}
[junit4] 2> 264294 INFO (jetty-closer-2145-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 264460 INFO (jetty-closer-2145-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@925835{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
[junit4] 2> 264460 INFO (jetty-closer-2145-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@188b46ed{/solr,null,STOPPED}
[junit4] 2> 264460 INFO (jetty-closer-2145-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 264555 INFO (jetty-closer-2145-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@482295be{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
[junit4] 2> 264555 INFO (jetty-closer-2145-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@35329f12{/solr,null,STOPPED}
[junit4] 2> 264555 INFO (jetty-closer-2145-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 264668 INFO (jetty-closer-2145-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@69bfdf2e{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
[junit4] 2> 264668 INFO (jetty-closer-2145-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@52e6b450{/solr,null,STOPPED}
[junit4] 2> 264668 INFO (jetty-closer-2145-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 264669 INFO (TEST-TestPackages.testAPI-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 264871 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 4 /solr/packages.json
[junit4] 2> 4 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 12 /solr/packages.json
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 19 /solr/live_nodes
[junit4] 2> 8 /solr/overseer/queue
[junit4] 2> 5 /solr/collections
[junit4] 2>
[junit4] 2> 264874 INFO (TEST-TestPackages.testAPI-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:34469
[junit4] 2> 264874 INFO (TEST-TestPackages.testAPI-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34469
[junit4] 2> 264874 INFO (TEST-TestPackages.testAPI-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34469
[junit4] 2> 264875 INFO (TEST-TestPackages.testAPI-seed#[8DF3F9FC89E1D363]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testAPI
[junit4] 2> 264876 INFO (SUITE-TestPackages-seed#[8DF3F9FC89E1D363]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 264876 INFO (SUITE-TestPackages-seed#[8DF3F9FC89E1D363]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:34469
[junit4] 2> 264876 INFO (SUITE-TestPackages-seed#[8DF3F9FC89E1D363]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34469
[junit4] 2> 264876 INFO (SUITE-TestPackages-seed#[8DF3F9FC89E1D363]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34469
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.pkg.TestPackages_8DF3F9FC89E1D363-001
[junit4] 2> Oct 31, 2020 1:27:30 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Lucene87, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=tk, timezone=America/Mazatlan
[junit4] 2> NOTE: Linux 5.4.0-52-generic amd64/AdoptOpenJDK 11.0.6 (64-bit)/cpus=16,threads=1,free=396361728,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [LargeFieldTest, MoveReplicaHDFSTest, DistributedFacetSimpleRefinementLongTailTest, TestSnapshotCloudManager, TestSolrCloudWithSecureImpersonation, FastVectorHighlighterTest, PolyFieldTest, ScheduledTriggerIntegrationTest, HdfsChaosMonkeyNothingIsSafeTest, SearchHandlerTest, TestBulkSchemaAPI, TestPseudoReturnFields, TestSimDistribStateManager, TestNumericRangeQuery64, SaslZkACLProviderTest, ReplicationFactorTest, TestSimNodeAddedTrigger, TestIntervalFaceting, PeerSyncWithIndexFingerprintCachingTest, TestCloudRecovery, TestPackages]
[junit4] Completed [230/921 (1!)] on J1 in 35.77s, 3 tests, 1 failure <<< FAILURES!
[...truncated 49977 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/4855/consoleText
[repro] Revision: 5b79ad3d64638556b04528bd941be3e41fc66a3a
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC"
[repro] Repro line: ant test -Dtestcase=TestPackages -Dtests.method=testPluginLoading -Dtests.seed=8DF3F9FC89E1D363 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=tk -Dtests.timezone=America/Mazatlan -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TestPackages
[repro] ant compile-test
[...truncated 3455 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestPackages" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC" -Dtests.seed=8DF3F9FC89E1D363 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=tk -Dtests.timezone=America/Mazatlan -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 91 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.pkg.TestPackages
[repro] Exiting with code 0
[...truncated 64 lines...]