You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2020/01/23 15:04:26 UTC
[JENKINS] Lucene-Solr-Tests-master - Build # 4067 - Still Unstable
Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/4067/
1 tests failed.
FAILED: org.apache.solr.schema.TestBulkSchemaConcurrent.test
Error Message:
[[], [field a1 not created, new type mystr1 not created, CopyField source=a1,dest=hello_lol1 not created, dynamic field *_lol1 not created], [], [], []]
Stack Trace:
java.lang.AssertionError: [[], [field a1 not created, new type mystr1 not created, CopyField source=a1,dest=hello_lol1 not created, dynamic field *_lol1 not created], [], [], []]
at __randomizedtesting.SeedInfo.seed([BB6A0ADC7BE584CF:333E3506D519E937]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.schema.TestBulkSchemaConcurrent.test(TestBulkSchemaConcurrent.java:94)
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:1754)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
at java.base/java.lang.Thread.run(Thread.java:834)
Build Log:
[...truncated 15820 lines...]
[junit4] Suite: org.apache.solr.schema.TestBulkSchemaConcurrent
[junit4] 2> 2730531 INFO (SUITE-TestBulkSchemaConcurrent-seed#[BB6A0ADC7BE584CF]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/data-dir-224-001
[junit4] 2> 2730531 WARN (SUITE-TestBulkSchemaConcurrent-seed#[BB6A0ADC7BE584CF]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 2730531 INFO (SUITE-TestBulkSchemaConcurrent-seed#[BB6A0ADC7BE584CF]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2730532 INFO (SUITE-TestBulkSchemaConcurrent-seed#[BB6A0ADC7BE584CF]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 2730532 INFO (SUITE-TestBulkSchemaConcurrent-seed#[BB6A0ADC7BE584CF]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2730532 INFO (SUITE-TestBulkSchemaConcurrent-seed#[BB6A0ADC7BE584CF]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /ay_d/z
[junit4] 2> 2730539 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2730540 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2730540 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2730734 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.ZkTestServer start zk server on port:42981
[junit4] 2> 2730734 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:42981
[junit4] 2> 2730734 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:42981
[junit4] 2> 2730734 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 42981
[junit4] 2> 2730737 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2730746 INFO (zkConnectionManagerCallback-17894-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2730746 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2730755 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2730758 INFO (zkConnectionManagerCallback-17896-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2730758 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2730760 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig-managed-schema.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 2730761 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 2730762 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 2730763 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 2730763 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 2730764 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 2730765 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 2730766 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 2730767 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 2730768 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 2730768 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 2730769 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 2730918 WARN (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 2730918 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2730918 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2730918 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 2730929 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2730929 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2730929 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2730930 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@49687a09{/ay_d/z,null,AVAILABLE}
[junit4] 2> 2730930 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1e956447{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:38097}
[junit4] 2> 2730930 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.e.j.s.Server Started @2730961ms
[junit4] 2> 2730930 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ay_d/z, solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/tempDir-001/control/data, hostPort=38097, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/control-001/cores}
[junit4] 2> 2730931 ERROR (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2730931 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2730931 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2730931 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2730931 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2730931 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T14:32:14.720256Z
[junit4] 2> 2730932 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2730934 INFO (zkConnectionManagerCallback-17898-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2730934 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2731035 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2731035 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/control-001/solr.xml
[junit4] 2> 2731038 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2731038 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2731039 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2731702 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2731703 WARN (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1d404e3b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2731703 WARN (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1d404e3b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2731705 WARN (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4c69e631[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2731705 WARN (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4c69e631[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2731707 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42981/solr
[junit4] 2> 2731710 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2731722 INFO (zkConnectionManagerCallback-17905-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2731725 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2731971 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2731972 INFO (zkConnectionManagerCallback-17907-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2731972 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2732043 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38097_ay_d%2Fz
[junit4] 2> 2732043 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.c.Overseer Overseer (id=73120032709935108-127.0.0.1:38097_ay_d%2Fz-n_0000000000) starting
[junit4] 2> 2732052 INFO (OverseerStateUpdate-73120032709935108-127.0.0.1:38097_ay_d%2Fz-n_0000000000) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:38097_ay_d%2Fz
[junit4] 2> 2732060 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38097_ay_d%2Fz
[junit4] 2> 2732062 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 2732063 WARN (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [n:127.0.0.1:38097_ay_d%2Fz ] 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> 2732069 INFO (zkCallback-17906-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2732112 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2732169 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96
[junit4] 2> 2732177 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96
[junit4] 2> 2732177 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96
[junit4] 2> 2732178 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/control-001/cores
[junit4] 2> 2732198 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2732202 INFO (zkConnectionManagerCallback-17916-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2732202 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2732203 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2732204 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42981/solr ready
[junit4] 2> 2732207 INFO (qtp774560062-47448) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:38097_ay_d%252Fz&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2732214 INFO (OverseerThreadFactory-13503-thread-1-processing-n:127.0.0.1:38097_ay_d%2Fz) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 2732321 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz x:control_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2732321 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz x:control_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2733332 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2733340 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 2733346 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 2733453 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2733467 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/conf1/managed-schema
[junit4] 2> 2733470 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/conf1/schema.xml to /configs/conf1/schema.xml.bak
[junit4] 2> 2733470 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 2733471 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96
[junit4] 2> 2733471 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 2733475 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=174, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2733490 WARN (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, registerPath=/solr,/v2, class=DumpRequestHandler},args = {defaults={a=A, b=B}}}
[junit4] 2> 2733540 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2733540 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2733542 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2733542 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2733543 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=460, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5145114256166464]
[junit4] 2> 2733543 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@53b28b2d[control_collection_shard1_replica_n1] main]
[junit4] 2> 2733545 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 2733545 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 2733545 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 2733546 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 2733546 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 2733546 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1656529813330984960
[junit4] 2> 2733549 INFO (searcherExecutor-13508-thread-1-processing-n:127.0.0.1:38097_ay_d%2Fz x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher Searcher@53b28b2d[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2733551 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 2733551 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/control_collection/leaders/shard1
[junit4] 2> 2733553 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2733553 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2733553 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:38097/ay_d/z/control_collection_shard1_replica_n1/
[junit4] 2> 2733554 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2733554 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:38097/ay_d/z/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 2733554 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/73120032709935108-core_node2-n_0000000000
[junit4] 2> 2733555 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:38097/ay_d/z/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 2733657 INFO (zkCallback-17906-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2733657 INFO (zkCallback-17906-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2733659 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2733661 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1340
[junit4] 2> 2733663 INFO (qtp774560062-47448) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 2733761 INFO (zkCallback-17906-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2733761 INFO (zkCallback-17906-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2733761 INFO (zkCallback-17906-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2733762 INFO (qtp774560062-47448) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:38097_ay_d%252Fz&wt=javabin&version=2} status=0 QTime=1555
[junit4] 2> 2733763 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Waiting to see 1 active replicas in collection: control_collection
[junit4] 2> 2733868 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2733870 INFO (zkConnectionManagerCallback-17922-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2733870 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2733871 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2733872 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42981/solr ready
[junit4] 2> 2733872 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 2733873 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2733878 INFO (OverseerCollectionConfigSetProcessor-73120032709935108-127.0.0.1:38097_ay_d%2Fz-n_0000000000) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2733879 INFO (OverseerThreadFactory-13503-thread-2-processing-n:127.0.0.1:38097_ay_d%2Fz) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 2734082 WARN (OverseerThreadFactory-13503-thread-2-processing-n:127.0.0.1:38097_ay_d%2Fz) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 2734083 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 2734084 INFO (qtp774560062-47450) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2} status=0 QTime=211
[junit4] 2> 2734085 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected:2
[junit4] 2> 2734085 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 2734085 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected:2
[junit4] 2> 2734085 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 2734085 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected:2
[junit4] 2> 2734085 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 2734085 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=4
[junit4] 2> 2734401 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/shard-1-001 of type NRT for shard2
[junit4] 2> 2734415 WARN (closeThreadPool-17923-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 2734415 INFO (closeThreadPool-17923-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2734415 INFO (closeThreadPool-17923-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2734415 INFO (closeThreadPool-17923-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 2734438 INFO (closeThreadPool-17923-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2734438 INFO (closeThreadPool-17923-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2734438 INFO (closeThreadPool-17923-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2734442 INFO (closeThreadPool-17923-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@68b6075{/ay_d/z,null,AVAILABLE}
[junit4] 2> 2734442 INFO (closeThreadPool-17923-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2724b72c{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:39823}
[junit4] 2> 2734442 INFO (closeThreadPool-17923-thread-1) [ ] o.e.j.s.Server Started @2734473ms
[junit4] 2> 2734442 INFO (closeThreadPool-17923-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ay_d/z, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/tempDir-001/jetty1, hostPort=39823, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/shard-1-001/cores}
[junit4] 2> 2734443 ERROR (closeThreadPool-17923-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2734443 INFO (closeThreadPool-17923-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2734443 INFO (closeThreadPool-17923-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2734443 INFO (closeThreadPool-17923-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2734443 INFO (closeThreadPool-17923-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2734443 INFO (closeThreadPool-17923-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T14:32:18.232238Z
[junit4] 2> 2734454 INFO (closeThreadPool-17923-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2734458 INFO (zkConnectionManagerCallback-17925-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2734458 INFO (closeThreadPool-17923-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2734560 INFO (closeThreadPool-17923-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2734560 INFO (closeThreadPool-17923-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/shard-1-001/solr.xml
[junit4] 2> 2734563 INFO (closeThreadPool-17923-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2734563 INFO (closeThreadPool-17923-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2734564 INFO (closeThreadPool-17923-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2734571 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/shard-2-001 of type NRT for shard1
[junit4] 2> 2734582 WARN (closeThreadPool-17923-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 2734582 INFO (closeThreadPool-17923-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2734582 INFO (closeThreadPool-17923-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2734582 INFO (closeThreadPool-17923-thread-2) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 2734834 INFO (closeThreadPool-17923-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2734834 INFO (closeThreadPool-17923-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2734834 INFO (closeThreadPool-17923-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2734839 INFO (closeThreadPool-17923-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6249aeb{/ay_d/z,null,AVAILABLE}
[junit4] 2> 2734840 INFO (closeThreadPool-17923-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3fdc217{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:42487}
[junit4] 2> 2734840 INFO (closeThreadPool-17923-thread-2) [ ] o.e.j.s.Server Started @2734870ms
[junit4] 2> 2734840 INFO (closeThreadPool-17923-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ay_d/z, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/tempDir-001/jetty2, hostPort=42487, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/shard-2-001/cores}
[junit4] 2> 2734840 ERROR (closeThreadPool-17923-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2734840 INFO (closeThreadPool-17923-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2734840 INFO (closeThreadPool-17923-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2734840 INFO (closeThreadPool-17923-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2734840 INFO (closeThreadPool-17923-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2734840 INFO (closeThreadPool-17923-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T14:32:18.629379Z
[junit4] 2> 2734846 INFO (closeThreadPool-17923-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2734864 INFO (zkConnectionManagerCallback-17928-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2734864 INFO (closeThreadPool-17923-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2734938 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/shard-3-001 of type NRT for shard2
[junit4] 2> 2734941 WARN (closeThreadPool-17923-thread-3) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 2734942 INFO (closeThreadPool-17923-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2734942 INFO (closeThreadPool-17923-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2734942 INFO (closeThreadPool-17923-thread-3) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 2734966 INFO (closeThreadPool-17923-thread-2) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2734966 INFO (closeThreadPool-17923-thread-2) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/shard-2-001/solr.xml
[junit4] 2> 2734969 INFO (closeThreadPool-17923-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2734969 INFO (closeThreadPool-17923-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2734970 INFO (closeThreadPool-17923-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2734982 INFO (closeThreadPool-17923-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2734982 INFO (closeThreadPool-17923-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2734982 INFO (closeThreadPool-17923-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2734990 INFO (closeThreadPool-17923-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5bde7ca0{/ay_d/z,null,AVAILABLE}
[junit4] 2> 2734990 INFO (closeThreadPool-17923-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@698dbbe1{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:34471}
[junit4] 2> 2734990 INFO (closeThreadPool-17923-thread-3) [ ] o.e.j.s.Server Started @2735021ms
[junit4] 2> 2734990 INFO (closeThreadPool-17923-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ay_d/z, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/tempDir-001/jetty3, hostPort=34471, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/shard-3-001/cores}
[junit4] 2> 2734991 ERROR (closeThreadPool-17923-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2734991 INFO (closeThreadPool-17923-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2734991 INFO (closeThreadPool-17923-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2734991 INFO (closeThreadPool-17923-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2734991 INFO (closeThreadPool-17923-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2734991 INFO (closeThreadPool-17923-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T14:32:18.780213Z
[junit4] 2> 2735010 INFO (closeThreadPool-17923-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2735023 INFO (zkConnectionManagerCallback-17931-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2735023 INFO (closeThreadPool-17923-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2735105 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/shard-4-001 of type NRT for shard1
[junit4] 2> 2735124 INFO (closeThreadPool-17923-thread-3) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2735124 INFO (closeThreadPool-17923-thread-3) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/shard-3-001/solr.xml
[junit4] 2> 2735125 WARN (closeThreadPool-17923-thread-4) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 2735125 INFO (closeThreadPool-17923-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2735125 INFO (closeThreadPool-17923-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 2735125 INFO (closeThreadPool-17923-thread-4) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 2735127 INFO (closeThreadPool-17923-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2735127 INFO (closeThreadPool-17923-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2735128 INFO (closeThreadPool-17923-thread-3) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2735794 INFO (closeThreadPool-17923-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2735794 INFO (closeThreadPool-17923-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2735794 INFO (closeThreadPool-17923-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2735810 INFO (closeThreadPool-17923-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@cb920b2{/ay_d/z,null,AVAILABLE}
[junit4] 2> 2735810 INFO (closeThreadPool-17923-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@41f0f119{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:32987}
[junit4] 2> 2735810 INFO (closeThreadPool-17923-thread-4) [ ] o.e.j.s.Server Started @2735841ms
[junit4] 2> 2735810 INFO (closeThreadPool-17923-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/ay_d/z, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/tempDir-001/jetty4, hostPort=32987, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/shard-4-001/cores}
[junit4] 2> 2735811 ERROR (closeThreadPool-17923-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2735811 INFO (closeThreadPool-17923-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2735811 INFO (closeThreadPool-17923-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 2735811 INFO (closeThreadPool-17923-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2735811 INFO (closeThreadPool-17923-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2735811 INFO (closeThreadPool-17923-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T14:32:19.600253Z
[junit4] 2> 2735830 INFO (closeThreadPool-17923-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2735846 INFO (zkConnectionManagerCallback-17934-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2735846 INFO (closeThreadPool-17923-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2735850 INFO (closeThreadPool-17923-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2735850 WARN (closeThreadPool-17923-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@58482517[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2735850 WARN (closeThreadPool-17923-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@58482517[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2735876 WARN (closeThreadPool-17923-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3bb5f2fc[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2735876 WARN (closeThreadPool-17923-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3bb5f2fc[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2735877 INFO (closeThreadPool-17923-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42981/solr
[junit4] 2> 2735892 INFO (closeThreadPool-17923-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2735902 INFO (zkConnectionManagerCallback-17940-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2735902 INFO (closeThreadPool-17923-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2735948 INFO (closeThreadPool-17923-thread-4) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 2735948 INFO (closeThreadPool-17923-thread-4) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/shard-4-001/solr.xml
[junit4] 2> 2735948 INFO (OverseerCollectionConfigSetProcessor-73120032709935108-127.0.0.1:38097_ay_d%2Fz-n_0000000000) [n:127.0.0.1:38097_ay_d%2Fz ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2735951 INFO (closeThreadPool-17923-thread-4) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2735951 INFO (closeThreadPool-17923-thread-4) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2735952 INFO (closeThreadPool-17923-thread-4) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2736174 INFO (closeThreadPool-17923-thread-2) [n:127.0.0.1:42487_ay_d%2Fz ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2736186 INFO (zkConnectionManagerCallback-17943-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2736186 INFO (closeThreadPool-17923-thread-2) [n:127.0.0.1:42487_ay_d%2Fz ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2736189 INFO (closeThreadPool-17923-thread-2) [n:127.0.0.1:42487_ay_d%2Fz ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2736191 INFO (closeThreadPool-17923-thread-2) [n:127.0.0.1:42487_ay_d%2Fz ] o.a.s.c.ZkController Publish node=127.0.0.1:42487_ay_d%2Fz as DOWN
[junit4] 2> 2736191 INFO (closeThreadPool-17923-thread-2) [n:127.0.0.1:42487_ay_d%2Fz ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2736191 INFO (closeThreadPool-17923-thread-2) [n:127.0.0.1:42487_ay_d%2Fz ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42487_ay_d%2Fz
[junit4] 2> 2736192 INFO (zkCallback-17921-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2736192 INFO (zkCallback-17906-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2736193 INFO (closeThreadPool-17923-thread-2) [n:127.0.0.1:42487_ay_d%2Fz ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 2736194 WARN (closeThreadPool-17923-thread-2) [n:127.0.0.1:42487_ay_d%2Fz ] 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> 2736204 INFO (zkCallback-17942-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2736226 INFO (closeThreadPool-17923-thread-2) [n:127.0.0.1:42487_ay_d%2Fz ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2736255 INFO (closeThreadPool-17923-thread-2) [n:127.0.0.1:42487_ay_d%2Fz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96
[junit4] 2> 2736264 INFO (closeThreadPool-17923-thread-2) [n:127.0.0.1:42487_ay_d%2Fz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96
[junit4] 2> 2736264 INFO (closeThreadPool-17923-thread-2) [n:127.0.0.1:42487_ay_d%2Fz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96
[junit4] 2> 2736265 INFO (closeThreadPool-17923-thread-2) [n:127.0.0.1:42487_ay_d%2Fz ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/shard-2-001/cores
[junit4] 2> 2736313 INFO (closeThreadPool-17923-thread-2) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:42487_ay_d%2Fz
[junit4] 2> 2736374 INFO (closeThreadPool-17923-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2736385 WARN (closeThreadPool-17923-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3ad8d258[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2736385 WARN (closeThreadPool-17923-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3ad8d258[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2736622 WARN (closeThreadPool-17923-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@aba8a8a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2736622 WARN (closeThreadPool-17923-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@aba8a8a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2736624 INFO (closeThreadPool-17923-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42981/solr
[junit4] 2> 2736640 INFO (closeThreadPool-17923-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2736647 INFO (zkConnectionManagerCallback-17953-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2736647 INFO (closeThreadPool-17923-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2736678 INFO (closeThreadPool-17923-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2736678 WARN (closeThreadPool-17923-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3ab0ad71[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2736678 WARN (closeThreadPool-17923-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3ab0ad71[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2736716 WARN (closeThreadPool-17923-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@45dfe7fc[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2736716 WARN (closeThreadPool-17923-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@45dfe7fc[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2736718 INFO (closeThreadPool-17923-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42981/solr
[junit4] 2> 2736738 INFO (closeThreadPool-17923-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2736746 INFO (zkConnectionManagerCallback-17959-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2736746 INFO (closeThreadPool-17923-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2736767 INFO (closeThreadPool-17923-thread-4) [n:127.0.0.1:32987_ay_d%2Fz ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2736775 INFO (zkConnectionManagerCallback-17961-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2736775 INFO (closeThreadPool-17923-thread-4) [n:127.0.0.1:32987_ay_d%2Fz ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2736778 INFO (closeThreadPool-17923-thread-4) [n:127.0.0.1:32987_ay_d%2Fz ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 2736990 INFO (closeThreadPool-17923-thread-3) [n:127.0.0.1:34471_ay_d%2Fz ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2737020 INFO (closeThreadPool-17923-thread-4) [n:127.0.0.1:32987_ay_d%2Fz ] o.a.s.c.ZkController Publish node=127.0.0.1:32987_ay_d%2Fz as DOWN
[junit4] 2> 2737021 INFO (closeThreadPool-17923-thread-4) [n:127.0.0.1:32987_ay_d%2Fz ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2737021 INFO (closeThreadPool-17923-thread-4) [n:127.0.0.1:32987_ay_d%2Fz ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:32987_ay_d%2Fz
[junit4] 2> 2737022 INFO (zkCallback-17942-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2737022 INFO (zkCallback-17906-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2737022 INFO (zkCallback-17921-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2737023 INFO (closeThreadPool-17923-thread-4) [n:127.0.0.1:32987_ay_d%2Fz ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 2737023 WARN (closeThreadPool-17923-thread-4) [n:127.0.0.1:32987_ay_d%2Fz ] 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> 2737030 INFO (zkConnectionManagerCallback-17965-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2737030 INFO (closeThreadPool-17923-thread-3) [n:127.0.0.1:34471_ay_d%2Fz ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2737032 INFO (zkCallback-17960-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 2737033 INFO (closeThreadPool-17923-thread-3) [n:127.0.0.1:34471_ay_d%2Fz ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 2737040 INFO (closeThreadPool-17923-thread-4) [n:127.0.0.1:32987_ay_d%2Fz ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2737047 INFO (closeThreadPool-17923-thread-3) [n:127.0.0.1:34471_ay_d%2Fz ] o.a.s.c.ZkController Publish node=127.0.0.1:34471_ay_d%2Fz as DOWN
[junit4] 2> 2737048 INFO (closeThreadPool-17923-thread-3) [n:127.0.0.1:34471_ay_d%2Fz ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2737048 INFO (closeThreadPool-17923-thread-3) [n:127.0.0.1:34471_ay_d%2Fz ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34471_ay_d%2Fz
[junit4] 2> 2737049 INFO (zkCallback-17921-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2737049 INFO (zkCallback-17942-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2737049 INFO (zkCallback-17906-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2737049 INFO (zkCallback-17960-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2737050 INFO (closeThreadPool-17923-thread-3) [n:127.0.0.1:34471_ay_d%2Fz ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 2737050 WARN (closeThreadPool-17923-thread-3) [n:127.0.0.1:34471_ay_d%2Fz ] 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> 2737063 INFO (closeThreadPool-17923-thread-4) [n:127.0.0.1:32987_ay_d%2Fz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96
[junit4] 2> 2737066 INFO (zkCallback-17964-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 2737074 INFO (closeThreadPool-17923-thread-4) [n:127.0.0.1:32987_ay_d%2Fz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96
[junit4] 2> 2737074 INFO (closeThreadPool-17923-thread-4) [n:127.0.0.1:32987_ay_d%2Fz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96
[junit4] 2> 2737075 INFO (closeThreadPool-17923-thread-4) [n:127.0.0.1:32987_ay_d%2Fz ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/shard-4-001/cores
[junit4] 2> 2737082 INFO (closeThreadPool-17923-thread-3) [n:127.0.0.1:34471_ay_d%2Fz ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2737122 INFO (closeThreadPool-17923-thread-3) [n:127.0.0.1:34471_ay_d%2Fz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96
[junit4] 2> 2737150 INFO (closeThreadPool-17923-thread-3) [n:127.0.0.1:34471_ay_d%2Fz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96
[junit4] 2> 2737150 INFO (closeThreadPool-17923-thread-3) [n:127.0.0.1:34471_ay_d%2Fz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96
[junit4] 2> 2737152 INFO (closeThreadPool-17923-thread-3) [n:127.0.0.1:34471_ay_d%2Fz ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/shard-3-001/cores
[junit4] 2> 2737342 INFO (closeThreadPool-17923-thread-4) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:32987_ay_d%2Fz
[junit4] 2> 2737348 INFO (closeThreadPool-17923-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2737350 WARN (closeThreadPool-17923-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@134815ad[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2737350 WARN (closeThreadPool-17923-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@134815ad[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2737362 WARN (closeThreadPool-17923-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@249c67b5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2737362 WARN (closeThreadPool-17923-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@249c67b5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2737364 INFO (closeThreadPool-17923-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42981/solr
[junit4] 2> 2737365 INFO (closeThreadPool-17923-thread-3) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:34471_ay_d%2Fz
[junit4] 2> 2737367 INFO (closeThreadPool-17923-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2737369 INFO (zkConnectionManagerCallback-17977-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2737369 INFO (closeThreadPool-17923-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2737471 INFO (closeThreadPool-17923-thread-1) [n:127.0.0.1:39823_ay_d%2Fz ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2737472 INFO (zkConnectionManagerCallback-17979-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2737472 INFO (closeThreadPool-17923-thread-1) [n:127.0.0.1:39823_ay_d%2Fz ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2737476 INFO (closeThreadPool-17923-thread-1) [n:127.0.0.1:39823_ay_d%2Fz ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2737479 INFO (closeThreadPool-17923-thread-1) [n:127.0.0.1:39823_ay_d%2Fz ] o.a.s.c.ZkController Publish node=127.0.0.1:39823_ay_d%2Fz as DOWN
[junit4] 2> 2737482 INFO (closeThreadPool-17923-thread-1) [n:127.0.0.1:39823_ay_d%2Fz ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2737482 INFO (closeThreadPool-17923-thread-1) [n:127.0.0.1:39823_ay_d%2Fz ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39823_ay_d%2Fz
[junit4] 2> 2737487 INFO (zkCallback-17964-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2737487 INFO (zkCallback-17921-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2737487 INFO (zkCallback-17960-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2737487 INFO (zkCallback-17906-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2737487 INFO (zkCallback-17942-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2737488 INFO (closeThreadPool-17923-thread-1) [n:127.0.0.1:39823_ay_d%2Fz ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 2737489 WARN (closeThreadPool-17923-thread-1) [n:127.0.0.1:39823_ay_d%2Fz ] 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> 2737500 INFO (zkCallback-17978-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 2737510 INFO (closeThreadPool-17923-thread-1) [n:127.0.0.1:39823_ay_d%2Fz ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2737529 INFO (closeThreadPool-17923-thread-1) [n:127.0.0.1:39823_ay_d%2Fz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96
[junit4] 2> 2737539 INFO (closeThreadPool-17923-thread-1) [n:127.0.0.1:39823_ay_d%2Fz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96
[junit4] 2> 2737539 INFO (closeThreadPool-17923-thread-1) [n:127.0.0.1:39823_ay_d%2Fz ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96
[junit4] 2> 2737540 INFO (closeThreadPool-17923-thread-1) [n:127.0.0.1:39823_ay_d%2Fz ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/shard-1-001/cores
[junit4] 2> 2737586 INFO (closeThreadPool-17923-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:39823_ay_d%2Fz
[junit4] 2> 2737591 INFO (qtp1171225976-47547) [n:127.0.0.1:32987_ay_d%2Fz ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:32987_ay_d%252Fz&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2737595 INFO (qtp1171225976-47549) [n:127.0.0.1:32987_ay_d%2Fz ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:34471_ay_d%252Fz&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2737599 INFO (qtp1171225976-47548) [n:127.0.0.1:32987_ay_d%2Fz ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:39823_ay_d%252Fz&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2737602 INFO (qtp1171225976-47546) [n:127.0.0.1:32987_ay_d%2Fz ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:42487_ay_d%252Fz&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2737607 INFO (OverseerThreadFactory-13503-thread-3-processing-n:127.0.0.1:38097_ay_d%2Fz) [n:127.0.0.1:38097_ay_d%2Fz c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:32987_ay_d%2Fz for creating new replica of shard shard1 for collection collection1
[junit4] 2> 2737608 INFO (OverseerThreadFactory-13503-thread-3-processing-n:127.0.0.1:38097_ay_d%2Fz) [n:127.0.0.1:38097_ay_d%2Fz c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 2737616 INFO (qtp1171225976-47544) [n:127.0.0.1:32987_ay_d%2Fz x:collection1_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n1&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2737617 INFO (OverseerThreadFactory-13503-thread-4-processing-n:127.0.0.1:38097_ay_d%2Fz) [n:127.0.0.1:38097_ay_d%2Fz c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:34471_ay_d%2Fz for creating new replica of shard shard2 for collection collection1
[junit4] 2> 2737619 INFO (OverseerThreadFactory-13503-thread-4-processing-n:127.0.0.1:38097_ay_d%2Fz) [n:127.0.0.1:38097_ay_d%2Fz c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 2737623 INFO (qtp1840394895-47534) [n:127.0.0.1:34471_ay_d%2Fz x:collection1_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n2&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2738630 INFO (qtp1840394895-47534) [n:127.0.0.1:34471_ay_d%2Fz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2738630 INFO (qtp1171225976-47544) [n:127.0.0.1:32987_ay_d%2Fz c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 2738649 INFO (qtp1840394895-47534) [n:127.0.0.1:34471_ay_d%2Fz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema [collection1_shard2_replica_n2] Schema name=test
[junit4] 2> 2738650 INFO (qtp1171225976-47544) [n:127.0.0.1:32987_ay_d%2Fz c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=test
[junit4] 2> 2738866 INFO (qtp1171225976-47544) [n:127.0.0.1:32987_ay_d%2Fz c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2738881 INFO (qtp1171225976-47544) [n:127.0.0.1:32987_ay_d%2Fz c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 2738881 INFO (qtp1171225976-47544) [n:127.0.0.1:32987_ay_d%2Fz c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard1.replica_n1' (registry 'solr.core.collection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96
[junit4] 2> 2738881 INFO (qtp1171225976-47544) [n:127.0.0.1:32987_ay_d%2Fz c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/shard-4-001/cores/collection1_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/shard-4-001/cores/collection1_shard1_replica_n1/data/]
[junit4] 2> 2738884 INFO (qtp1171225976-47544) [n:127.0.0.1:32987_ay_d%2Fz c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=174, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 2738892 INFO (qtp1840394895-47534) [n:127.0.0.1:34471_ay_d%2Fz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2738902 WARN (qtp1171225976-47544) [n:127.0.0.1:32987_ay_d%2Fz c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, registerPath=/solr,/v2, class=DumpRequestHandler},args = {defaults={a=A, b=B}}}
[junit4] 2> 2738915 INFO (qtp1840394895-47534) [n:127.0.0.1:34471_ay_d%2Fz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n2' using configuration from configset conf1, trusted=true
[junit4] 2> 2738915 INFO (qtp1840394895-47534) [n:127.0.0.1:34471_ay_d%2Fz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard2.replica_n2' (registry 'solr.core.collection1.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@73fe0a96
[junit4] 2> 2738915 INFO (qtp1840394895-47534) [n:127.0.0.1:34471_ay_d%2Fz c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrCore [[collection1_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001/shard-3-001/cores/collection1_shard2_replica_n2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/../../../../../../../../../../home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tes
[...truncated too long message...]
estBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 3443069 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> 17 /solr/collections/collection1/state.json
[junit4] 2> 17 /solr/configs/conf1/managed-schema
[junit4] 2> 15 /solr/configs/conf1
[junit4] 2> 7 /solr/aliases.json
[junit4] 2> 5 /solr/packages.json
[junit4] 2> 5 /solr/security.json
[junit4] 2> 5 /solr/collections/collection1/terms/shard1
[junit4] 2> 5 /solr/collections/collection1/terms/shard2
[junit4] 2> 4 /solr/collections/collection1/collectionprops.json
[junit4] 2> 2 /solr/collections/control_collection/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 62 /solr/clusterstate.json
[junit4] 2> 12 /solr/collections/control_collection/state.json
[junit4] 2> 10 /solr/configs/conf1/managed-schema
[junit4] 2> 7 /solr/clusterprops.json
[junit4] 2> 2 /solr/overseer_elect/election/73120032709935119-127.0.0.1:32987_ay_d%2Fz-n_0000000002
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 39 /solr/overseer/queue
[junit4] 2> 35 /solr/live_nodes
[junit4] 2> 15 /solr/overseer/collection-queue-work
[junit4] 2> 7 /solr/collections
[junit4] 2>
[junit4] 2> 3443085 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:42981
[junit4] 2> 3443085 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:42981
[junit4] 2> 3443085 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[BB6A0ADC7BE584CF]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 42981
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestBulkSchemaConcurrent -Dtests.method=test -Dtests.seed=BB6A0ADC7BE584CF -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=ka-GE -Dtests.timezone=America/St_Kitts -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 713s J2 | TestBulkSchemaConcurrent.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: [[], [field a1 not created, new type mystr1 not created, CopyField source=a1,dest=hello_lol1 not created, dynamic field *_lol1 not created], [], [], []]
[junit4] > at __randomizedtesting.SeedInfo.seed([BB6A0ADC7BE584CF:333E3506D519E937]:0)
[junit4] > at org.apache.solr.schema.TestBulkSchemaConcurrent.test(TestBulkSchemaConcurrent.java:94)
[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:566)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.schema.TestBulkSchemaConcurrent_BB6A0ADC7BE584CF-001
[junit4] 2> Jan 23, 2020 2:44:06 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=Asserting(Lucene84): {}, docValues:{}, maxPointsInLeafNode=1137, maxMBSortInHeap=7.728468418055196, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4eb8ea37), locale=ka-GE, timezone=America/St_Kitts
[junit4] 2> NOTE: Linux 4.15.0-54-generic amd64/Oracle Corporation 11.0.4 (64-bit)/cpus=4,threads=1,free=106187960,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestRealTimeGet, TestStressLiveNodes, TestEmbeddedSolrServerConstructors, TestDocSet, AuthToolTest, SliceStateTest, TestCustomStream, PreAnalyzedFieldManagedSchemaCloudTest, TestClusterProperties, TestSchemaSimilarityResource, HdfsUnloadDistributedZkTest, TestRebalanceLeaders, TestSnapshotCloudManager, TestCollationFieldDocValues, TestLazyCores, EmbeddedSolrNoSerializeTest, TimeZoneUtilsTest, RoutingToNodesWithPropertiesTest, DebugComponentTest, TestBlobHandler, TestCloudRecovery, ResponseBuilderTest, MaxSizeAutoCommitTest, CdcrBootstrapTest, TestGraphMLResponseWriter, DeleteNodeTest, FullSolrCloudDistribCmdsTest, TestCodecSupport, TestSolrCloudWithSecureImpersonation, CdcrRequestHandlerTest, DocValuesMissingTest, CoreAdminOperationTest, TestSolrQueryParser, TestQueryingOnDownCollection, TestXmlQParser, RangeFacetCloudTest, QueryResultKeyTest, SolrCoreTest, TestWriterPerf, FastVectorHighlighterTest, TestStressLucene, SecurityConfHandlerTest, TestHttpServletCarrier, StatelessScriptUpdateProcessorFactoryTest, TestCollectionsAPIViaSolrCloudCluster, ReplicationFactorTest, TestNonDefinedSimilarityFactory, TestLMDirichletSimilarityFactory, SimpleCollectionCreateDeleteTest, SuggestComponentContextFilterQueryTest, TestLockTree, TestScoreJoinQPNoScore, MoveReplicaHDFSTest, TestReplicaProperties, SolrXmlInZkTest, CursorMarkTest, TestPrepRecovery, RemoteQueryErrorTest, TestTolerantSearch, SolrShardReporterTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestFunctionQuery, MigrateRouteKeyTest, RequiredFieldsTest, ProtectedTermFilterFactoryTest, TestMaxScoreQueryParser, CurrencyFieldTypeTest, TestSimExtremeIndexing, TlogReplayBufferedWhileIndexingTest, TestSolrXml, TestQuerySenderListener, BJQFilterAccessibleTest, TestSolr4Spatial, MetricTriggerIntegrationTest, DeleteStatusTest, NumericFieldsTest, TaggerTest, SimplePostToolTest, SolrIndexConfigTest, RulesTest, JWTVerificationkeyResolverTest, NodeMarkersRegistrationTest, TestLeaderElectionWithEmptyReplica, TestStressUserVersions, TestIndexSearcher, MergeStrategyTest, TestSafeXMLParsing, TolerantUpdateProcessorTest, NodeAddedTriggerTest, TestRuleBasedAuthorizationPlugin, TestSubQueryTransformerCrossCore, TestChildDocTransformerHierarchy, HLLSerializationTest, TestConfigSetImmutable, HdfsChaosMonkeyNothingIsSafeTest, ExitableDirectoryReaderTest, BitVectorTest, TestAtomicUpdateErrorCases, CollectionsAPIDistributedZkTest, TestDFISimilarityFactory, WordBreakSolrSpellCheckerTest, TestInPlaceUpdateWithRouteField, SimpleMLTQParserTest, ZkStateWriterTest, TestSolrCoreParser, DaemonStreamApiTest, FieldMutatingUpdateProcessorTest, PingRequestHandlerTest, IndexSizeTriggerMixedBoundsTest, SolrJmxReporterTest, TestExpandComponent, TestCSVResponseWriter, TestRestoreCore, TestImpersonationWithHadoopAuth, TestPhraseSuggestions, HighlighterTest, SolrTestCaseJ4Test, HttpPartitionTest, ShardsWhitelistTest, TestSystemIdResolver, AutoscalingHistoryHandlerTest, TestSolrConfigHandlerCloud, TriggerSetPropertiesIntegrationTest, DistributedTermsComponentTest, CollectionStateFormat2Test, XmlUpdateRequestHandlerTest, DimensionalRoutedAliasUpdateProcessorTest, TestPointFields, TestSegmentSorting, BasicAuthIntegrationTest, TestMultiWordSynonyms, SchemaVersionSpecificBehaviorTest, TestExclusionRuleCollectionAccess, TestMaxTokenLenTokenizer, TestRetrieveFieldsOptimizer, TestSearcherReuse, TestUseDocValuesAsStored, IndexBasedSpellCheckerTest, TestDistribIDF, AutoAddReplicasPlanActionTest, ScriptEngineTest, ZkSolrClientTest, AtomicUpdateProcessorFactoryTest, ShardRoutingCustomTest, TestFieldCollectionResource, TestCloudConsistency, TestFieldSortValues, TestRandomFaceting, BasicDistributedZk2Test, BasicZkTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, ClusterStateTest, CollectionsAPISolrJTest, LeaderElectionContextKeyTest, LeaderElectionIntegrationTest, LegacyCloudClusterPropTest, MultiSolrCloudTestCaseTest, MultiThreadedOCPTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerModifyCollectionTest, OverseerTaskQueueTest, PeerSyncReplicationTest, TestConfigSetsAPIExclusivity, TestConfigSetsAPIZkFailure, TestCryptoKeys, TestSizeLimitedDistributedMap, TestSkipOverseerOperations, TestTolerantUpdateProcessorRandomCloud, TestWaitForStateWithJettyShutdowns, VMParamsZkACLAndCredentialsProvidersTest, ZkCLITest, CollectionReloadTest, ComputePlanActionTest, TestSimClusterStateProvider, TestSimComputePlanAction, TestSimDistribStateManager, TestSimGenericDistributedQueue, TestSimLargeCluster, TestDynamicURP, TestShardHandlerFactory, TestSimpleTextCodec, TestSolrDeletionPolicy1, TestXIncludeConfig, TestSolrCloudSnapshots, TestCoreBackup, TestReplicationHandler, TestHttpShardHandlerFactory, TestPostingsSolrHighlighter, TestSlowCompositeReaderWrapper, UninvertDocValuesMergePolicyTest, TestLegacyField, TestLegacyFieldReuse, TestLegacyNumericUtils, TestLegacyTerms, TestMultiValuedNumericRangeQuery, TestNumericRangeQuery64, MetricsConfigTest, SolrMetricManagerTest, SolrMetricReporterTest, SolrMetricsIntegrationTest, SolrRrdBackendFactoryTest, SubstringBytesRefFilterTest, TestFacetMethods, TestFaceting, TestRemoteStreaming, TestMacroExpander, TestMacros, JSONWriterTest, TestJavabinTupleStreamParser, TestPHPSerializedResponseWriter, TestManagedResource, TestManagedResourceStorage, TestRestManager, TestBulkSchemaAPI, BooleanFieldTest, DocValuesMultiTest, DocValuesTest, SynonymTokenizerTest, TestBinaryField, TestBulkSchemaConcurrent]
[junit4] Completed [842/900 (1!)] on J2 in 713.64s, 1 test, 1 failure <<< FAILURES!
[...truncated 52656 lines...]
[JENKINS] Lucene-Solr-Tests-master - Build # 4068 - Still Unstable
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/4068/
1 tests failed.
FAILED: org.apache.solr.schema.TestBulkSchemaConcurrent.test
Error Message:
[[CopyField source=a0,dest=hello_lol0 not created, new type mystr0 not created, dynamic field *_lol0 not created, field a0 not created], [], [], [], []]
Stack Trace:
java.lang.AssertionError: [[CopyField source=a0,dest=hello_lol0 not created, new type mystr0 not created, dynamic field *_lol0 not created, field a0 not created], [], [], [], []]
at __randomizedtesting.SeedInfo.seed([FF7F8B340F4B5E59:772BB4EEA1B733A1]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.schema.TestBulkSchemaConcurrent.test(TestBulkSchemaConcurrent.java:94)
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:1754)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
at java.base/java.lang.Thread.run(Thread.java:834)
Build Log:
[...truncated 14709 lines...]
[junit4] Suite: org.apache.solr.schema.TestBulkSchemaConcurrent
[junit4] 2> 1465901 INFO (SUITE-TestBulkSchemaConcurrent-seed#[FF7F8B340F4B5E59]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1465902 INFO (SUITE-TestBulkSchemaConcurrent-seed#[FF7F8B340F4B5E59]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/data-dir-122-001
[junit4] 2> 1465902 WARN (SUITE-TestBulkSchemaConcurrent-seed#[FF7F8B340F4B5E59]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=9 numCloses=9
[junit4] 2> 1465902 INFO (SUITE-TestBulkSchemaConcurrent-seed#[FF7F8B340F4B5E59]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1465903 INFO (SUITE-TestBulkSchemaConcurrent-seed#[FF7F8B340F4B5E59]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1465903 INFO (SUITE-TestBulkSchemaConcurrent-seed#[FF7F8B340F4B5E59]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 1465906 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1465907 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1465907 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1466007 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.ZkTestServer start zk server on port:41825
[junit4] 2> 1466007 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:41825
[junit4] 2> 1466007 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:41825
[junit4] 2> 1466007 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 41825
[junit4] 2> 1466009 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1466013 INFO (zkConnectionManagerCallback-5677-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1466013 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1466015 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1466021 INFO (zkConnectionManagerCallback-5679-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1466021 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1466022 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig-managed-schema.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 1466023 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 1466025 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1466025 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 1466026 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 1466027 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 1466028 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 1466029 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1466030 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1466031 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 1466031 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.ZkTestServer put /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 1466032 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 1466180 WARN (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1466180 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1466180 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1466180 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 1466181 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1466181 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1466181 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1466181 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1c11031{/,null,AVAILABLE}
[junit4] 2> 1466181 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@454fadf0{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:36479}
[junit4] 2> 1466181 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.e.j.s.Server Started @1466223ms
[junit4] 2> 1466181 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/tempDir-001/control/data, hostPort=36479, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/control-001/cores, replicaType=NRT}
[junit4] 2> 1466182 ERROR (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1466182 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1466182 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 1466182 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1466182 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1466182 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T18:52:05.436268Z
[junit4] 2> 1466184 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1466186 INFO (zkConnectionManagerCallback-5681-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1466186 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1466287 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1466287 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/control-001/solr.xml
[junit4] 2> 1466290 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1466290 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1466291 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1467093 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1467094 WARN (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@28f60386[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1467094 WARN (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@28f60386[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1467099 WARN (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@188da14e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1467099 WARN (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@188da14e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1467100 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41825/solr
[junit4] 2> 1467101 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1467105 INFO (zkConnectionManagerCallback-5688-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1467105 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1467211 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [n:127.0.0.1:36479_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1467212 INFO (zkConnectionManagerCallback-5690-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1467212 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [n:127.0.0.1:36479_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1467271 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [n:127.0.0.1:36479_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:36479_
[junit4] 2> 1467272 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [n:127.0.0.1:36479_ ] o.a.s.c.Overseer Overseer (id=73121054469849092-127.0.0.1:36479_-n_0000000000) starting
[junit4] 2> 1467275 INFO (OverseerStateUpdate-73121054469849092-127.0.0.1:36479_-n_0000000000) [n:127.0.0.1:36479_ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:36479_
[junit4] 2> 1467282 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [n:127.0.0.1:36479_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36479_
[junit4] 2> 1467285 INFO (zkCallback-5689-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1467286 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [n:127.0.0.1:36479_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1467286 WARN (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [n:127.0.0.1:36479_ ] 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> 1467304 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [n:127.0.0.1:36479_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1467325 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [n:127.0.0.1:36479_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229
[junit4] 2> 1467332 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [n:127.0.0.1:36479_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229
[junit4] 2> 1467332 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [n:127.0.0.1:36479_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229
[junit4] 2> 1467333 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [n:127.0.0.1:36479_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/control-001/cores
[junit4] 2> 1467350 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1467351 INFO (zkConnectionManagerCallback-5699-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1467351 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1467352 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1467354 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41825/solr ready
[junit4] 2> 1467362 INFO (qtp1480216886-17283) [n:127.0.0.1:36479_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:36479_&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1467365 INFO (OverseerThreadFactory-6270-thread-1-processing-n:127.0.0.1:36479_) [n:127.0.0.1:36479_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 1467476 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ x:control_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1467476 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ x:control_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1468487 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1468495 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 1468502 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 1468619 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1468639 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/conf1/managed-schema
[junit4] 2> 1468643 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/conf1/schema.xml to /configs/conf1/schema.xml.bak
[junit4] 2> 1468643 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 1468643 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229
[junit4] 2> 1468643 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 1468648 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=273, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1468665 WARN (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, registerPath=/solr,/v2, class=DumpRequestHandler},args = {defaults={a=A, b=B}}}
[junit4] 2> 1468714 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1468714 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1468715 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1468715 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1468716 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=129, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1468717 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5b00e533[control_collection_shard1_replica_n1] main]
[junit4] 2> 1468719 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1468719 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1468719 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 1468720 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1468720 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1468721 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1656546161301913600
[junit4] 2> 1468724 INFO (searcherExecutor-6275-thread-1-processing-n:127.0.0.1:36479_ x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher Searcher@5b00e533[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1468726 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 1468726 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/control_collection/leaders/shard1
[junit4] 2> 1468729 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1468729 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1468729 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:36479/control_collection_shard1_replica_n1/
[junit4] 2> 1468730 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1468730 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:36479/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 1468730 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/73121054469849092-core_node2-n_0000000000
[junit4] 2> 1468731 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:36479/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 1468833 INFO (zkCallback-5689-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1468834 INFO (zkCallback-5689-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1468838 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1468840 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1365
[junit4] 2> 1468842 INFO (qtp1480216886-17283) [n:127.0.0.1:36479_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 1468941 INFO (zkCallback-5689-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1468941 INFO (zkCallback-5689-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1468941 INFO (zkCallback-5689-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 1468942 INFO (qtp1480216886-17283) [n:127.0.0.1:36479_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:36479_&wt=javabin&version=2} status=0 QTime=1579
[junit4] 2> 1468942 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Waiting to see 1 active replicas in collection: control_collection
[junit4] 2> 1469046 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1469049 INFO (zkConnectionManagerCallback-5705-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1469049 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1469050 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1469051 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41825/solr ready
[junit4] 2> 1469051 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 1469052 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1469057 INFO (OverseerThreadFactory-6270-thread-2-processing-n:127.0.0.1:36479_) [n:127.0.0.1:36479_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 1469057 INFO (OverseerCollectionConfigSetProcessor-73121054469849092-127.0.0.1:36479_-n_0000000000) [n:127.0.0.1:36479_ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1469261 WARN (OverseerThreadFactory-6270-thread-2-processing-n:127.0.0.1:36479_) [n:127.0.0.1:36479_ ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 1469263 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 1469264 INFO (qtp1480216886-17286) [n:127.0.0.1:36479_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2} status=0 QTime=212
[junit4] 2> 1469265 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected:2
[junit4] 2> 1469265 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 1469265 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected:2
[junit4] 2> 1469265 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 1469265 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 2 expected:2
[junit4] 2> 1469265 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 1469265 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=4
[junit4] 2> 1469433 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/shard-1-001 of type NRT for shard2
[junit4] 2> 1469435 WARN (closeThreadPool-5706-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1469435 INFO (closeThreadPool-5706-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1469435 INFO (closeThreadPool-5706-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1469435 INFO (closeThreadPool-5706-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 1469461 INFO (closeThreadPool-5706-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1469462 INFO (closeThreadPool-5706-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1469462 INFO (closeThreadPool-5706-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1469469 INFO (closeThreadPool-5706-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@30361795{/,null,AVAILABLE}
[junit4] 2> 1469470 INFO (closeThreadPool-5706-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3cca7d1b{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:35725}
[junit4] 2> 1469470 INFO (closeThreadPool-5706-thread-1) [ ] o.e.j.s.Server Started @1469511ms
[junit4] 2> 1469470 INFO (closeThreadPool-5706-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/tempDir-001/jetty1, hostPort=35725, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/shard-1-001/cores}
[junit4] 2> 1469475 ERROR (closeThreadPool-5706-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1469475 INFO (closeThreadPool-5706-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1469475 INFO (closeThreadPool-5706-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 1469475 INFO (closeThreadPool-5706-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1469475 INFO (closeThreadPool-5706-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1469475 INFO (closeThreadPool-5706-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T18:52:08.729184Z
[junit4] 2> 1469482 INFO (closeThreadPool-5706-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1469507 INFO (zkConnectionManagerCallback-5708-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1469507 INFO (closeThreadPool-5706-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1469612 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/shard-2-001 of type NRT for shard1
[junit4] 2> 1469618 INFO (closeThreadPool-5706-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1469618 INFO (closeThreadPool-5706-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/shard-1-001/solr.xml
[junit4] 2> 1469620 WARN (closeThreadPool-5706-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1469620 INFO (closeThreadPool-5706-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1469621 INFO (closeThreadPool-5706-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1469621 INFO (closeThreadPool-5706-thread-2) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 1469621 INFO (closeThreadPool-5706-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1469621 INFO (closeThreadPool-5706-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1469622 INFO (closeThreadPool-5706-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1469685 INFO (closeThreadPool-5706-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1469685 INFO (closeThreadPool-5706-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1469685 INFO (closeThreadPool-5706-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1469698 INFO (closeThreadPool-5706-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7f5e2d32{/,null,AVAILABLE}
[junit4] 2> 1469698 INFO (closeThreadPool-5706-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4012b36{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:37453}
[junit4] 2> 1469698 INFO (closeThreadPool-5706-thread-2) [ ] o.e.j.s.Server Started @1469740ms
[junit4] 2> 1469698 INFO (closeThreadPool-5706-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/tempDir-001/jetty2, hostPort=37453, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/shard-2-001/cores}
[junit4] 2> 1469699 ERROR (closeThreadPool-5706-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1469699 INFO (closeThreadPool-5706-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1469699 INFO (closeThreadPool-5706-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 1469699 INFO (closeThreadPool-5706-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1469699 INFO (closeThreadPool-5706-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1469699 INFO (closeThreadPool-5706-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T18:52:08.953384Z
[junit4] 2> 1469708 INFO (closeThreadPool-5706-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1469726 INFO (zkConnectionManagerCallback-5711-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1469726 INFO (closeThreadPool-5706-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1469828 INFO (closeThreadPool-5706-thread-2) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1469828 INFO (closeThreadPool-5706-thread-2) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/shard-2-001/solr.xml
[junit4] 2> 1469832 INFO (closeThreadPool-5706-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1469832 INFO (closeThreadPool-5706-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1469834 INFO (closeThreadPool-5706-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1469852 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/shard-3-001 of type NRT for shard2
[junit4] 2> 1469863 WARN (closeThreadPool-5706-thread-3) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1469863 INFO (closeThreadPool-5706-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1469863 INFO (closeThreadPool-5706-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1469863 INFO (closeThreadPool-5706-thread-3) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 1469973 INFO (closeThreadPool-5706-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1469973 INFO (closeThreadPool-5706-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1469973 INFO (closeThreadPool-5706-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1469979 INFO (closeThreadPool-5706-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4a40432d{/,null,AVAILABLE}
[junit4] 2> 1469979 INFO (closeThreadPool-5706-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@795b911f{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:40575}
[junit4] 2> 1469979 INFO (closeThreadPool-5706-thread-3) [ ] o.e.j.s.Server Started @1470021ms
[junit4] 2> 1469979 INFO (closeThreadPool-5706-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/tempDir-001/jetty3, hostPort=40575, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/shard-3-001/cores}
[junit4] 2> 1469980 ERROR (closeThreadPool-5706-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1469980 INFO (closeThreadPool-5706-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1469980 INFO (closeThreadPool-5706-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 1469980 INFO (closeThreadPool-5706-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1469980 INFO (closeThreadPool-5706-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1469980 INFO (closeThreadPool-5706-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T18:52:09.234318Z
[junit4] 2> 1470005 INFO (closeThreadPool-5706-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1470021 INFO (zkConnectionManagerCallback-5714-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1470021 INFO (closeThreadPool-5706-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1470031 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/shard-4-001 of type NRT for shard1
[junit4] 2> 1470031 WARN (closeThreadPool-5706-thread-4) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1470031 INFO (closeThreadPool-5706-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1470031 INFO (closeThreadPool-5706-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1470031 INFO (closeThreadPool-5706-thread-4) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 11.0.4+10-LTS
[junit4] 2> 1470037 INFO (closeThreadPool-5706-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1470037 INFO (closeThreadPool-5706-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1470037 INFO (closeThreadPool-5706-thread-4) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1470038 INFO (closeThreadPool-5706-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6b7ade6b{/,null,AVAILABLE}
[junit4] 2> 1470038 INFO (closeThreadPool-5706-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7dd63ff{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:46307}
[junit4] 2> 1470038 INFO (closeThreadPool-5706-thread-4) [ ] o.e.j.s.Server Started @1470080ms
[junit4] 2> 1470038 INFO (closeThreadPool-5706-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/tempDir-001/jetty4, hostPort=46307, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/shard-4-001/cores}
[junit4] 2> 1470038 ERROR (closeThreadPool-5706-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1470038 INFO (closeThreadPool-5706-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1470038 INFO (closeThreadPool-5706-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 1470038 INFO (closeThreadPool-5706-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1470038 INFO (closeThreadPool-5706-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1470038 INFO (closeThreadPool-5706-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-01-23T18:52:09.292805Z
[junit4] 2> 1470039 INFO (closeThreadPool-5706-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1470051 INFO (zkConnectionManagerCallback-5716-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1470051 INFO (closeThreadPool-5706-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1470122 INFO (closeThreadPool-5706-thread-3) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1470122 INFO (closeThreadPool-5706-thread-3) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/shard-3-001/solr.xml
[junit4] 2> 1470126 INFO (closeThreadPool-5706-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1470126 INFO (closeThreadPool-5706-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1470127 INFO (closeThreadPool-5706-thread-3) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1470139 INFO (closeThreadPool-5706-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1470140 WARN (closeThreadPool-5706-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7280ee81[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1470140 WARN (closeThreadPool-5706-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7280ee81[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1470142 WARN (closeThreadPool-5706-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@f9c162c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1470142 WARN (closeThreadPool-5706-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@f9c162c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1470143 INFO (closeThreadPool-5706-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41825/solr
[junit4] 2> 1470144 INFO (closeThreadPool-5706-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1470145 INFO (zkConnectionManagerCallback-5723-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1470145 INFO (closeThreadPool-5706-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1470227 INFO (closeThreadPool-5706-thread-4) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1470227 INFO (closeThreadPool-5706-thread-4) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/shard-4-001/solr.xml
[junit4] 2> 1470229 INFO (closeThreadPool-5706-thread-4) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1470229 INFO (closeThreadPool-5706-thread-4) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1470230 INFO (closeThreadPool-5706-thread-4) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1470341 INFO (closeThreadPool-5706-thread-1) [n:127.0.0.1:35725_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1470355 INFO (zkConnectionManagerCallback-5726-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1470355 INFO (closeThreadPool-5706-thread-1) [n:127.0.0.1:35725_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1470359 INFO (closeThreadPool-5706-thread-1) [n:127.0.0.1:35725_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1470361 INFO (closeThreadPool-5706-thread-1) [n:127.0.0.1:35725_ ] o.a.s.c.ZkController Publish node=127.0.0.1:35725_ as DOWN
[junit4] 2> 1470361 INFO (closeThreadPool-5706-thread-1) [n:127.0.0.1:35725_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1470361 INFO (closeThreadPool-5706-thread-1) [n:127.0.0.1:35725_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35725_
[junit4] 2> 1470362 INFO (zkCallback-5689-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1470362 INFO (zkCallback-5704-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1470363 INFO (closeThreadPool-5706-thread-1) [n:127.0.0.1:35725_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1470363 WARN (closeThreadPool-5706-thread-1) [n:127.0.0.1:35725_ ] 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> 1470373 INFO (zkCallback-5725-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1470401 INFO (closeThreadPool-5706-thread-1) [n:127.0.0.1:35725_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1470551 INFO (closeThreadPool-5706-thread-1) [n:127.0.0.1:35725_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229
[junit4] 2> 1470562 INFO (closeThreadPool-5706-thread-1) [n:127.0.0.1:35725_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229
[junit4] 2> 1470562 INFO (closeThreadPool-5706-thread-1) [n:127.0.0.1:35725_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229
[junit4] 2> 1470574 INFO (closeThreadPool-5706-thread-1) [n:127.0.0.1:35725_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/shard-1-001/cores
[junit4] 2> 1470678 INFO (closeThreadPool-5706-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:35725_
[junit4] 2> 1470893 INFO (closeThreadPool-5706-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1470894 WARN (closeThreadPool-5706-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5953bb64[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1470894 WARN (closeThreadPool-5706-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5953bb64[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1470897 WARN (closeThreadPool-5706-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7efafb21[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1470897 WARN (closeThreadPool-5706-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7efafb21[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1470898 INFO (closeThreadPool-5706-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41825/solr
[junit4] 2> 1470899 INFO (closeThreadPool-5706-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1470900 INFO (zkConnectionManagerCallback-5736-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1470900 INFO (closeThreadPool-5706-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1471002 INFO (closeThreadPool-5706-thread-4) [n:127.0.0.1:46307_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1471003 INFO (zkConnectionManagerCallback-5738-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1471003 INFO (closeThreadPool-5706-thread-4) [n:127.0.0.1:46307_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1471006 INFO (closeThreadPool-5706-thread-4) [n:127.0.0.1:46307_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1471009 INFO (closeThreadPool-5706-thread-4) [n:127.0.0.1:46307_ ] o.a.s.c.ZkController Publish node=127.0.0.1:46307_ as DOWN
[junit4] 2> 1471009 INFO (closeThreadPool-5706-thread-4) [n:127.0.0.1:46307_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1471010 INFO (closeThreadPool-5706-thread-4) [n:127.0.0.1:46307_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46307_
[junit4] 2> 1471011 INFO (zkCallback-5704-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1471011 INFO (zkCallback-5689-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1471011 INFO (zkCallback-5725-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1471014 INFO (closeThreadPool-5706-thread-4) [n:127.0.0.1:46307_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1471015 WARN (closeThreadPool-5706-thread-4) [n:127.0.0.1:46307_ ] 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> 1471025 INFO (zkCallback-5737-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1471033 INFO (closeThreadPool-5706-thread-4) [n:127.0.0.1:46307_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1471051 INFO (closeThreadPool-5706-thread-4) [n:127.0.0.1:46307_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229
[junit4] 2> 1471058 INFO (OverseerCollectionConfigSetProcessor-73121054469849092-127.0.0.1:36479_-n_0000000000) [n:127.0.0.1:36479_ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1471061 INFO (closeThreadPool-5706-thread-4) [n:127.0.0.1:46307_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229
[junit4] 2> 1471061 INFO (closeThreadPool-5706-thread-4) [n:127.0.0.1:46307_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229
[junit4] 2> 1471062 INFO (closeThreadPool-5706-thread-4) [n:127.0.0.1:46307_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/shard-4-001/cores
[junit4] 2> 1471092 INFO (closeThreadPool-5706-thread-4) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:46307_
[junit4] 2> 1471567 INFO (closeThreadPool-5706-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1471568 WARN (closeThreadPool-5706-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@326ed21e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1471568 WARN (closeThreadPool-5706-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@326ed21e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1471570 WARN (closeThreadPool-5706-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@561fe46a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1471570 WARN (closeThreadPool-5706-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@561fe46a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1471571 INFO (closeThreadPool-5706-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41825/solr
[junit4] 2> 1471572 INFO (closeThreadPool-5706-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1471573 INFO (zkConnectionManagerCallback-5748-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1471573 INFO (closeThreadPool-5706-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1471675 INFO (closeThreadPool-5706-thread-3) [n:127.0.0.1:40575_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1471676 INFO (zkConnectionManagerCallback-5750-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1471676 INFO (closeThreadPool-5706-thread-3) [n:127.0.0.1:40575_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1471680 INFO (closeThreadPool-5706-thread-3) [n:127.0.0.1:40575_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1471682 INFO (closeThreadPool-5706-thread-3) [n:127.0.0.1:40575_ ] o.a.s.c.ZkController Publish node=127.0.0.1:40575_ as DOWN
[junit4] 2> 1471683 INFO (closeThreadPool-5706-thread-3) [n:127.0.0.1:40575_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1471683 INFO (closeThreadPool-5706-thread-3) [n:127.0.0.1:40575_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40575_
[junit4] 2> 1471684 INFO (zkCallback-5737-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1471684 INFO (zkCallback-5725-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1471684 INFO (zkCallback-5689-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1471684 INFO (zkCallback-5704-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1471685 INFO (zkCallback-5749-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1471686 INFO (closeThreadPool-5706-thread-3) [n:127.0.0.1:40575_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1471686 WARN (closeThreadPool-5706-thread-3) [n:127.0.0.1:40575_ ] 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> 1471701 INFO (closeThreadPool-5706-thread-3) [n:127.0.0.1:40575_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1471719 INFO (closeThreadPool-5706-thread-3) [n:127.0.0.1:40575_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229
[junit4] 2> 1471731 INFO (closeThreadPool-5706-thread-3) [n:127.0.0.1:40575_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229
[junit4] 2> 1471731 INFO (closeThreadPool-5706-thread-3) [n:127.0.0.1:40575_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229
[junit4] 2> 1471733 INFO (closeThreadPool-5706-thread-3) [n:127.0.0.1:40575_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/shard-3-001/cores
[junit4] 2> 1471777 INFO (closeThreadPool-5706-thread-3) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:40575_
[junit4] 2> 1471819 INFO (closeThreadPool-5706-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 1471820 WARN (closeThreadPool-5706-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@30ebe2bf[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1471820 WARN (closeThreadPool-5706-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@30ebe2bf[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1471822 WARN (closeThreadPool-5706-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@46deb692[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1471822 WARN (closeThreadPool-5706-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@46deb692[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1471824 INFO (closeThreadPool-5706-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41825/solr
[junit4] 2> 1471824 INFO (closeThreadPool-5706-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1471829 INFO (zkConnectionManagerCallback-5760-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1471829 INFO (closeThreadPool-5706-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1471931 INFO (closeThreadPool-5706-thread-2) [n:127.0.0.1:37453_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1471933 INFO (zkConnectionManagerCallback-5762-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1471933 INFO (closeThreadPool-5706-thread-2) [n:127.0.0.1:37453_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1471937 INFO (closeThreadPool-5706-thread-2) [n:127.0.0.1:37453_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1471940 INFO (closeThreadPool-5706-thread-2) [n:127.0.0.1:37453_ ] o.a.s.c.ZkController Publish node=127.0.0.1:37453_ as DOWN
[junit4] 2> 1471941 INFO (closeThreadPool-5706-thread-2) [n:127.0.0.1:37453_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1471941 INFO (closeThreadPool-5706-thread-2) [n:127.0.0.1:37453_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37453_
[junit4] 2> 1471942 INFO (zkCallback-5737-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1471942 INFO (zkCallback-5689-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1471942 INFO (zkCallback-5725-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1471942 INFO (zkCallback-5704-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1471942 INFO (zkCallback-5749-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1471943 INFO (zkCallback-5761-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 1471944 INFO (closeThreadPool-5706-thread-2) [n:127.0.0.1:37453_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1471944 WARN (closeThreadPool-5706-thread-2) [n:127.0.0.1:37453_ ] 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> 1471958 INFO (closeThreadPool-5706-thread-2) [n:127.0.0.1:37453_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1471974 INFO (closeThreadPool-5706-thread-2) [n:127.0.0.1:37453_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229
[junit4] 2> 1471984 INFO (closeThreadPool-5706-thread-2) [n:127.0.0.1:37453_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229
[junit4] 2> 1471984 INFO (closeThreadPool-5706-thread-2) [n:127.0.0.1:37453_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229
[junit4] 2> 1471985 INFO (closeThreadPool-5706-thread-2) [n:127.0.0.1:37453_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/shard-2-001/cores
[junit4] 2> 1472032 INFO (closeThreadPool-5706-thread-2) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:37453_
[junit4] 2> 1472039 INFO (qtp955641565-17343) [n:127.0.0.1:35725_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:46307_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1472042 INFO (qtp955641565-17344) [n:127.0.0.1:35725_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:40575_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1472051 INFO (qtp955641565-17341) [n:127.0.0.1:35725_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:35725_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1472052 INFO (qtp955641565-17340) [n:127.0.0.1:35725_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:37453_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1472052 INFO (OverseerThreadFactory-6270-thread-3-processing-n:127.0.0.1:36479_) [n:127.0.0.1:36479_ c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:46307_ for creating new replica of shard shard1 for collection collection1
[junit4] 2> 1472054 INFO (OverseerThreadFactory-6270-thread-4-processing-n:127.0.0.1:36479_) [n:127.0.0.1:36479_ c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:40575_ for creating new replica of shard shard2 for collection collection1
[junit4] 2> 1472055 INFO (OverseerThreadFactory-6270-thread-3-processing-n:127.0.0.1:36479_) [n:127.0.0.1:36479_ c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1472055 INFO (OverseerThreadFactory-6270-thread-4-processing-n:127.0.0.1:36479_) [n:127.0.0.1:36479_ c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 1472060 INFO (qtp65202011-17381) [n:127.0.0.1:46307_ x:collection1_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n1&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1472062 INFO (qtp2103712363-17368) [n:127.0.0.1:40575_ x:collection1_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n2&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1473071 INFO (qtp2103712363-17368) [n:127.0.0.1:40575_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1473071 INFO (qtp65202011-17381) [n:127.0.0.1:46307_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1473084 INFO (qtp2103712363-17368) [n:127.0.0.1:40575_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema [collection1_shard2_replica_n2] Schema name=test
[junit4] 2> 1473085 INFO (qtp65202011-17381) [n:127.0.0.1:46307_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=test
[junit4] 2> 1473211 INFO (qtp65202011-17381) [n:127.0.0.1:46307_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1473214 INFO (qtp2103712363-17368) [n:127.0.0.1:40575_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1473225 INFO (qtp65202011-17381) [n:127.0.0.1:46307_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 1473225 INFO (qtp65202011-17381) [n:127.0.0.1:46307_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard1.replica_n1' (registry 'solr.core.collection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229
[junit4] 2> 1473225 INFO (qtp65202011-17381) [n:127.0.0.1:46307_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/shard-4-001/cores/collection1_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/shard-4-001/cores/collection1_shard1_replica_n1/data/]
[junit4] 2> 1473227 INFO (qtp2103712363-17368) [n:127.0.0.1:40575_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n2' using configuration from configset conf1, trusted=true
[junit4] 2> 1473228 INFO (qtp2103712363-17368) [n:127.0.0.1:40575_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard2.replica_n2' (registry 'solr.core.collection1.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1eb71229
[junit4] 2> 1473228 INFO (qtp2103712363-17368) [n:127.0.0.1:40575_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrCore [[collection1_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/shard-3-001/cores/collection1_shard2_replica_n2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001/shard-3-001/cores/collection1_shard2_replica_n2/data/]
[junit4] 2> 1473229 INFO (qtp65202011-17381) [n:127.0.0.1:46307_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=273, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1473232 INFO (qtp2103712363-17368) [n:127.0.0.1:40575_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=273, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1473254 WARN (qtp65202011-17381) [n:127.0.0.1:46307_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, registerPath=/solr,/v2, class=DumpRequestHandler},args = {defaults={a=A, b=B}}}
[junit4] 2> 1473254 WARN (qtp2103712363-17368) [n:127.0.0.1:40575_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, registerPath=/solr,/v2, class=DumpRequestHandler},args = {defaults={a=A, b=B}}}
[junit4] 2> 1473310 INFO (qtp2103712363-17368) [n:127.0.0.1:40575_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1473310 INFO (qtp2103712363-17368) [n:127.0.0.1:40575_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1473312 INFO (qtp2103712363-17368) [n:127.0.0.1:40575_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1473312 INFO (qtp2103712363-17368) [n:127.0.0.1:40575_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1473313 INFO (qtp2103712363-17368) [n:127.0.0.1:40575_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=129, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1473313 INFO (qtp2103712363-17368) [n:127.0.0.1:40575_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4613850d[collection1_shard2_replica_n2] main]
[junit4] 2> 1473315 INFO (qtp2103712363-17368) [n:127.0.0.1:40575_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1473315 INFO (qtp2103712363-17368) [n:127.0.0.1:40575_ c:collection1 s:shard2 x:collec
[...truncated too long message...]
ng metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2176547 INFO (closeThreadPool-5814-thread-1) [ ] o.a.s.c.Overseer Overseer (id=73121054469849092-127.0.0.1:36479_-n_0000000000) closing
[junit4] 2> 2176645 INFO (closeThreadPool-5796-thread-2) [ ] o.a.s.c.Overseer Overseer (id=73121054469849092-127.0.0.1:36479_-n_0000000000) closing
[junit4] 2> 2176646 INFO (zkCallback-5725-thread-7) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (2)
[junit4] 2> 2176646 INFO (zkCallback-5761-thread-5) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (2)
[junit4] 2> 2176648 INFO (closeThreadPool-5796-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@454fadf0{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 2176649 INFO (closeThreadPool-5796-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1c11031{/,null,UNAVAILABLE}
[junit4] 2> 2176649 INFO (closeThreadPool-5796-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@7dd63ff{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 2176649 INFO (closeThreadPool-5796-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2176649 INFO (closeThreadPool-5796-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6b7ade6b{/,null,UNAVAILABLE}
[junit4] 2> 2176650 INFO (closeThreadPool-5796-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2176749 INFO (closeThreadPool-5796-thread-7) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@795b911f{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 2176750 INFO (closeThreadPool-5796-thread-7) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4a40432d{/,null,UNAVAILABLE}
[junit4] 2> 2176750 INFO (closeThreadPool-5796-thread-7) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2176850 INFO (closeThreadPool-5796-thread-6) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@3cca7d1b{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 2176851 INFO (closeThreadPool-5796-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4012b36{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 2176851 INFO (closeThreadPool-5796-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7f5e2d32{/,null,UNAVAILABLE}
[junit4] 2> 2176851 INFO (closeThreadPool-5796-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2176851 INFO (closeThreadPool-5796-thread-6) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@30361795{/,null,UNAVAILABLE}
[junit4] 2> 2176852 INFO (closeThreadPool-5796-thread-6) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2176855 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 2177094 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> 17 /solr/collections/collection1/state.json
[junit4] 2> 17 /solr/configs/conf1/managed-schema
[junit4] 2> 15 /solr/configs/conf1
[junit4] 2> 7 /solr/aliases.json
[junit4] 2> 5 /solr/packages.json
[junit4] 2> 5 /solr/security.json
[junit4] 2> 5 /solr/collections/collection1/terms/shard1
[junit4] 2> 5 /solr/collections/collection1/terms/shard2
[junit4] 2> 4 /solr/collections/collection1/collectionprops.json
[junit4] 2> 2 /solr/collections/control_collection/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 61 /solr/clusterstate.json
[junit4] 2> 12 /solr/collections/control_collection/state.json
[junit4] 2> 10 /solr/configs/conf1/managed-schema
[junit4] 2> 7 /solr/clusterprops.json
[junit4] 2> 2 /solr/overseer_elect/election/73121054469849100-127.0.0.1:35725_-n_0000000001
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 38 /solr/overseer/queue
[junit4] 2> 33 /solr/live_nodes
[junit4] 2> 18 /solr/overseer/collection-queue-work
[junit4] 2> 7 /solr/collections
[junit4] 2>
[junit4] 2> 2177108 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:41825
[junit4] 2> 2177108 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:41825
[junit4] 2> 2177108 INFO (TEST-TestBulkSchemaConcurrent.test-seed#[FF7F8B340F4B5E59]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 41825
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestBulkSchemaConcurrent -Dtests.method=test -Dtests.seed=FF7F8B340F4B5E59 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=da-GL -Dtests.timezone=Indian/Reunion -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 711s J1 | TestBulkSchemaConcurrent.test <<<
[junit4] > Throwable #1: java.lang.AssertionError: [[CopyField source=a0,dest=hello_lol0 not created, new type mystr0 not created, dynamic field *_lol0 not created, field a0 not created], [], [], [], []]
[junit4] > at __randomizedtesting.SeedInfo.seed([FF7F8B340F4B5E59:772BB4EEA1B733A1]:0)
[junit4] > at org.apache.solr.schema.TestBulkSchemaConcurrent.test(TestBulkSchemaConcurrent.java:94)
[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:566)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:834)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.schema.TestBulkSchemaConcurrent_FF7F8B340F4B5E59-001
[junit4] 2> Jan 23, 2020 7:03:56 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=Asserting(Lucene84): {}, docValues:{}, maxPointsInLeafNode=1609, maxMBSortInHeap=6.8951215369101035, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@2db1ae), locale=da-GL, timezone=Indian/Reunion
[junit4] 2> NOTE: Linux 4.15.0-54-generic amd64/Oracle Corporation 11.0.4 (64-bit)/cpus=4,threads=1,free=350313192,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [CollectionsAPIAsyncDistributedZkTest, MultiSolrCloudTestCaseTest, TestDynamicFieldResource, NodeLostTriggerTest, HttpSolrCallGetCoreTest, TestNestedDocsSort, TriggerIntegrationTest, UUIDUpdateProcessorFallbackTest, PKIAuthenticationIntegrationTest, TestFileDictionaryLookup, TestStressThreadBackup, TestLegacyFieldReuse, ClassificationUpdateProcessorIntegrationTest, ScheduledMaintenanceTriggerTest, TestIntervalFaceting, SpellCheckCollatorWithCollapseTest, TestDownShardTolerantSearch, BJQParserTest, TestDefaultStatsCache, SpatialRPTFieldTypeTest, SaslZkACLProviderTest, DistributedFacetPivotLongTailTest, TestSQLHandlerNonCloud, TestOverriddenPrefixQueryForCustomFieldType, TestUnifiedSolrHighlighter, SyncSliceTest, PrimitiveFieldTypeTest, MoreLikeThisHandlerTest, PluginInfoTest, TestJsonFacets, TestCloudPseudoReturnFields, TestStandardQParsers, TestDistributedStatsComponentCardinality, DistributedFacetSimpleRefinementLongTailTest, TestSubQueryTransformer, QueryElevationComponentTest, HttpPartitionOnCommitTest, ShardSplitTest, ParsingFieldUpdateProcessorsTest, TestGeoJSONResponseWriter, BasicDistributedZk2Test, DistributedQueryComponentOptimizationTest, TestSizeLimitedDistributedMap, TestMacros, SearchHandlerTest, HdfsNNFailoverTest, FullHLLTest, PrimUtilsTest, DefaultValueUpdateProcessorTest, ForceLeaderWithTlogReplicasTest, TestSolrIndexConfig, DocValuesTest, ZkShardTermsTest, TestFieldCache, TestDistribPackageStore, DocExpirationUpdateProcessorFactoryTest, DateRangeFieldTest, UpdateLogTest, TestHighlightDedupGrouping, TestRandomFaceting, TestImplicitCoreProperties, TestComplexPhraseLeadingWildcard, SolrCoreCheckLockOnStartupTest, CoreAdminHandlerTest, JavabinLoaderTest, TestJettySolrRunner, RuleEngineTest, PeerSyncReplicationTest, TestSimPolicyCloud, TestZkAclsWithHadoopAuth, TestBinaryResponseWriter, TestRandomRequestDistribution, TestConfigReload, TestPseudoReturnFields, SharedFSAutoReplicaFailoverTest, CollectionPropsTest, TestNumericTokenStream, DeleteShardTest, SolrJmxReporterCloudTest, TestMissingGroups, ZkStateReaderTest, TestClassicSimilarityFactory, SearchRateTriggerIntegrationTest, XmlInterpolationTest, TestPerFieldSimilarityWithDefaultOverride, StatsReloadRaceTest, Tagger2Test, ZookeeperStatusHandlerTest, TestBadConfig, LeaderFailoverAfterPartitionTest, TestManagedSynonymFilterFactory, CustomTermsComponentTest, PropertiesRequestHandlerTest, TestStressInPlaceUpdates, DOMUtilTest, StatsComponentTest, SuggesterTSTTest, DistributedExpandComponentTest, TestBulkSchemaConcurrent]
[junit4] Completed [499/900 (1!)] on J1 in 711.69s, 1 test, 1 failure <<< FAILURES!
[...truncated 53773 lines...]