You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/05/20 16:18:37 UTC
[JENKINS-EA] Lucene-Solr-master-Windows (64bit/jdk-15-ea+16) -
Build # 8857 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8857/
Java: 64bit/jdk-15-ea+16 -XX:-UseCompressedOops -XX:+UseG1GC
2 tests failed.
FAILED: org.apache.solr.update.TestInPlaceUpdatesDistrib.test
Error Message:
Error from server at http://127.0.0.1:64097/collection1: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J2\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\index-NIOFSDirectory-003\_w_3.fnm: Too many open files
Stack Trace:
org.apache.solr.client.solrj.impl.BaseHttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:64097/collection1: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J2\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\index-NIOFSDirectory-003\_w_3.fnm: Too many open files
at __randomizedtesting.SeedInfo.seed([A53AF3431E7CACB3:2D6ECC99B080C14B]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:663)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1147)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:910)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:842)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
at org.apache.solr.client.solrj.SolrClient.commit(SolrClient.java:505)
at org.apache.solr.client.solrj.SolrClient.commit(SolrClient.java:480)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.commit(AbstractFullDistribZkTestBase.java:1760)
at org.apache.solr.update.TestInPlaceUpdatesDistrib.docValuesUpdateTest(TestInPlaceUpdatesDistrib.java:468)
at org.apache.solr.update.TestInPlaceUpdatesDistrib.test(TestInPlaceUpdatesDistrib.java:162)
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:564)
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:1081)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1053)
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:832)
FAILED: org.apache.solr.update.TestInPlaceUpdatesDistrib.test
Error Message:
Error from server at http://127.0.0.1:56484/collection1: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\index-NIOFSDirectory-003\_w_3_Asserting_0.dvm: Too many open files
Stack Trace:
org.apache.solr.client.solrj.impl.BaseHttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:56484/collection1: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\index-NIOFSDirectory-003\_w_3_Asserting_0.dvm: Too many open files
at __randomizedtesting.SeedInfo.seed([A53AF3431E7CACB3:2D6ECC99B080C14B]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:663)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1147)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:910)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:842)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
at org.apache.solr.client.solrj.SolrClient.commit(SolrClient.java:505)
at org.apache.solr.client.solrj.SolrClient.commit(SolrClient.java:480)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.commit(AbstractFullDistribZkTestBase.java:1760)
at org.apache.solr.update.TestInPlaceUpdatesDistrib.docValuesUpdateTest(TestInPlaceUpdatesDistrib.java:452)
at org.apache.solr.update.TestInPlaceUpdatesDistrib.test(TestInPlaceUpdatesDistrib.java:162)
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:564)
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:1081)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1053)
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:832)
Build Log:
[...truncated 13717 lines...]
[junit4] Suite: org.apache.solr.update.TestInPlaceUpdatesDistrib
[junit4] 2> 434474 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\server\solr\configsets\_default\conf'
[junit4] 2> 434474 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\data-dir-92-001
[junit4] 2> 434474 WARN (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=5 numCloses=5
[junit4] 2> 434474 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 434474 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 434474 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 434474 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 434478 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.SolrTestCaseJ4 updateLog impl=solr.CdcrUpdateLog
[junit4] 2> 434478 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 434485 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 434504 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.s.IndexSchema Schema name=inplace-updates
[junit4] 2> 434506 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.s.IndexSchema Loaded schema inplace-updates/1.6 with uniqueid field id
[junit4] 2> 434822 WARN (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.f.DistribPackageStore Unable to create [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\filestore] directory in SOLR_HOME [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr]. Features requiring this directory may fail.
[junit4] 2> => java.security.AccessControlException: access denied ("java.io.FilePermission" "C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\filestore" "write")
[junit4] 2> at java.base/java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
[junit4] 2> java.security.AccessControlException: access denied ("java.io.FilePermission" "C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\filestore" "write")
[junit4] 2> at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:?]
[junit4] 2> at java.security.AccessController.checkPermission(AccessController.java:1036) ~[?:?]
[junit4] 2> at java.lang.SecurityManager.checkPermission(SecurityManager.java:408) ~[?:?]
[junit4] 2> at java.lang.SecurityManager.checkWrite(SecurityManager.java:838) ~[?:?]
[junit4] 2> at java.io.File.mkdir(File.java:1326) ~[?:?]
[junit4] 2> at java.io.File.mkdirs(File.java:1358) ~[?:?]
[junit4] 2> at org.apache.solr.filestore.DistribPackageStore.ensurePackageStoreDir(DistribPackageStore.java:520) ~[java/:?]
[junit4] 2> at org.apache.solr.filestore.DistribPackageStore.<init>(DistribPackageStore.java:77) ~[java/:?]
[junit4] 2> at org.apache.solr.filestore.PackageStoreAPI.<init>(PackageStoreAPI.java:79) ~[java/:?]
[junit4] 2> at org.apache.solr.core.CoreContainer.load(CoreContainer.java:633) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:170) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:142) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:148) ~[java/:?]
[junit4] 2> at org.apache.solr.util.TestHarness.<init>(TestHarness.java:111) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:814) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:804) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:665) ~[java/:?]
[junit4] 2> at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:654) ~[java/:?]
[junit4] 2> at org.apache.solr.update.TestInPlaceUpdatesDistrib.beforeSuperClass(TestInPlaceUpdatesDistrib.java:90) ~[test/:?]
[junit4] 2> at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
[junit4] 2> at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
[junit4] 2> at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:564) ~[?:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:882) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) ~[java/:?]
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) ~[java/:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826) ~[randomizedtesting-runner-2.7.6.jar:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:832) [?:?]
[junit4] 2> 434822 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 434826 WARN (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@496076c1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 434826 WARN (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@496076c1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 434828 WARN (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5d074ca6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 434828 WARN (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5d074ca6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 434829 WARN (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] 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> 434850 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 434850 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 434866 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3250a117
[junit4] 2> 434871 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3250a117
[junit4] 2> 434871 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3250a117
[junit4] 2> 434879 INFO (coreLoadExecutor-4343-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 434891 INFO (coreLoadExecutor-4343-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=inplace-updates
[junit4] 2> 434894 INFO (coreLoadExecutor-4343-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema inplace-updates/1.6 with uniqueid field id
[junit4] 2> 434894 INFO (coreLoadExecutor-4343-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1, trusted=true
[junit4] 2> 434894 INFO (coreLoadExecutor-4343-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3250a117
[junit4] 2> 434894 INFO (coreLoadExecutor-4343-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\data-dir-92-001\]
[junit4] 2> 434903 WARN (coreLoadExecutor-4343-thread-1) [ x:collection1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A, b=B}}}
[junit4] 2> 434965 INFO (coreLoadExecutor-4343-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 434966 INFO (coreLoadExecutor-4343-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 434967 INFO (coreLoadExecutor-4343-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 434967 INFO (coreLoadExecutor-4343-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 434971 WARN (coreLoadExecutor-4343-thread-1) [ x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf ; switching to use InMemory storage instead.
[junit4] 2> 434971 INFO (coreLoadExecutor-4343-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 434971 INFO (coreLoadExecutor-4343-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1667223759305572352
[junit4] 2> 434972 INFO (searcherExecutor-4345-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms: Collection: 'null'
[junit4] 2> 434973 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 434978 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 434978 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 434978 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 435089 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.ZkTestServer start zk server on port: 56349
[junit4] 2> 435089 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:56349
[junit4] 2> 435089 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:56349
[junit4] 2> 435089 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 56349
[junit4] 2> 435092 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 435095 INFO (zkConnectionManagerCallback-4352-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 435095 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 435097 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 435099 INFO (zkConnectionManagerCallback-4354-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 435099 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 435099 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 435099 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\schema-inplace-updates.xml to /configs/conf1/schema.xml
[junit4] 2> 435104 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 435105 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 435106 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 435108 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 435109 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 435110 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 435111 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 435112 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 435114 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 435115 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 435869 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 435869 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 435869 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 15-ea+16-681
[junit4] 2> 435870 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 435870 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 435870 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 435870 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@375ef689{/,null,AVAILABLE}
[junit4] 2> 435871 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@90a1f69{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:56374}
[junit4] 2> 435871 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.e.j.s.Server Started @421124ms
[junit4] 2> 435871 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\tempDir-001/control/data, hostPort=56374, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\control-001\cores}
[junit4] 2> 435871 ERROR (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 435871 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 435871 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 435871 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 435871 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 435871 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-20T15:27:59.852063200Z
[junit4] 2> 435872 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 435874 INFO (zkConnectionManagerCallback-4356-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 435874 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 435990 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 435990 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\control-001\solr.xml
[junit4] 2> 435996 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@3250a117, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 436103 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 436104 WARN (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4772e52[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 436104 WARN (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4772e52[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 436108 WARN (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@63051994[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 436108 WARN (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@63051994[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 436110 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56349/solr
[junit4] 2> 436111 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 436113 INFO (zkConnectionManagerCallback-4367-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 436113 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 436228 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [n:127.0.0.1:56374_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 436232 INFO (zkConnectionManagerCallback-4369-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 436232 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [n:127.0.0.1:56374_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 436456 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [n:127.0.0.1:56374_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:56374_
[junit4] 2> 436456 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [n:127.0.0.1:56374_ ] o.a.s.c.Overseer Overseer (id=72075615043977220-127.0.0.1:56374_-n_0000000000) starting
[junit4] 2> 436464 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [n:127.0.0.1:56374_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56374_
[junit4] 2> 436465 INFO (OverseerStateUpdate-72075615043977220-127.0.0.1:56374_-n_0000000000) [n:127.0.0.1:56374_ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:56374_
[junit4] 2> 436467 INFO (OverseerStateUpdate-72075615043977220-127.0.0.1:56374_-n_0000000000) [n:127.0.0.1:56374_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 436471 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [n:127.0.0.1:56374_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 436471 WARN (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [n:127.0.0.1:56374_ ] 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> 436494 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [n:127.0.0.1:56374_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 436516 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [n:127.0.0.1:56374_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3250a117
[junit4] 2> 436523 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [n:127.0.0.1:56374_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3250a117
[junit4] 2> 436524 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [n:127.0.0.1:56374_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3250a117
[junit4] 2> 436524 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [n:127.0.0.1:56374_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\control-001\cores
[junit4] 2> 436540 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 436542 INFO (zkConnectionManagerCallback-4386-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 436542 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 436544 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 436545 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56349/solr ready
[junit4] 2> 436548 INFO (qtp134526904-6298) [n:127.0.0.1:56374_ ] 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:56374_&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 436552 INFO (OverseerThreadFactory-4376-thread-1-processing-n:127.0.0.1:56374_) [n:127.0.0.1:56374_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 436690 INFO (qtp134526904-6300) [n:127.0.0.1:56374_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 436692 INFO (qtp134526904-6300) [n:127.0.0.1:56374_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 436697 INFO (qtp134526904-6297) [n:127.0.0.1:56374_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 436708 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ 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> 437836 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 437849 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=inplace-updates
[junit4] 2> 437855 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema inplace-updates/1.6 with uniqueid field id
[junit4] 2> 437856 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ 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> 437856 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ 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@3250a117
[junit4] 2> 437857 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ 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 [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\control-001\cores\control_collection_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\control-001\cores\control_collection_shard1_replica_n1\data\]
[junit4] 2> 437904 WARN (qtp134526904-6299) [n:127.0.0.1:56374_ 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, class=DumpRequestHandler},args = {defaults={a=A, b=B}}}
[junit4] 2> 438031 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 438031 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ 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> 438035 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 438035 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 438040 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 438040 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ 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> 438044 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 438044 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ 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 1667223762527846400
[junit4] 2> 438047 INFO (searcherExecutor-4388-thread-1-processing-n:127.0.0.1:56374_ x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:56374_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher autowarm time: 0 ms: Collection: 'control_collection'
[junit4] 2> 438087 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ 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> 438087 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ 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> 438091 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 438091 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ 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> 438091 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:56374/control_collection_shard1_replica_n1/
[junit4] 2> 438091 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ 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> 438091 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:56374/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 438091 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ 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/72075615043977220-core_node2-n_0000000000
[junit4] 2> 438098 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ 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:56374/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 438221 INFO (zkCallback-4368-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> 438226 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ 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> 438229 INFO (zkCallback-4368-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> 438232 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ ] 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=1385
[junit4] 2> 438235 INFO (qtp134526904-6298) [n:127.0.0.1:56374_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 438341 INFO (zkCallback-4368-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> 438341 INFO (zkCallback-4368-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> 438341 INFO (zkCallback-4368-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> 438341 INFO (qtp134526904-6298) [n:127.0.0.1:56374_ ] 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:56374_&wt=javabin&version=2} status=0 QTime=1632
[junit4] 2> 438341 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Waiting to see 1 active replicas in collection: control_collection
[junit4] 2> 438477 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 438483 INFO (zkConnectionManagerCallback-4397-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 438483 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 438483 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 438486 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56349/solr ready
[junit4] 2> 438486 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 438488 INFO (qtp134526904-6298) [n:127.0.0.1:56374_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=&stateFormat=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 438494 INFO (OverseerCollectionConfigSetProcessor-72075615043977220-127.0.0.1:56374_-n_0000000000) [n:127.0.0.1:56374_ ] 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> 438494 INFO (OverseerThreadFactory-4376-thread-2-processing-n:127.0.0.1:56374_) [n:127.0.0.1:56374_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 438720 WARN (OverseerThreadFactory-4376-thread-2-processing-n:127.0.0.1:56374_) [n:127.0.0.1:56374_ ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 438722 INFO (qtp134526904-6298) [n:127.0.0.1:56374_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 438722 INFO (qtp134526904-6298) [n:127.0.0.1:56374_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=&stateFormat=1&wt=javabin&version=2} status=0 QTime=212
[junit4] 2> 438724 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 1 expected: 1
[junit4] 2> 438724 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 438724 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 1 expected: 1
[junit4] 2> 438724 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 438724 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.SolrCloudTestCase active slice count: 1 expected: 1
[junit4] 2> 438724 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.SolrCloudTestCase active replica count: 0 expected replica count: 0
[junit4] 2> 438724 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=3
[junit4] 2> 439750 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\shard-1-001 of type NRT for shard1
[junit4] 2> 439752 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 439752 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 439752 INFO (closeThreadPool-4398-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 15-ea+16-681
[junit4] 2> 439752 INFO (closeThreadPool-4398-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 439752 INFO (closeThreadPool-4398-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 439752 INFO (closeThreadPool-4398-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 439752 INFO (closeThreadPool-4398-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1ca87bff{/,null,AVAILABLE}
[junit4] 2> 439757 INFO (closeThreadPool-4398-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@790067f6{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:56484}
[junit4] 2> 439757 INFO (closeThreadPool-4398-thread-1) [ ] o.e.j.s.Server Started @424657ms
[junit4] 2> 439757 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\tempDir-001/jetty1, hostPort=56484, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\shard-1-001\cores, replicaType=NRT}
[junit4] 2> 439757 ERROR (closeThreadPool-4398-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 439757 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 439758 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 439758 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 439758 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 439758 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-20T15:28:03.739113700Z
[junit4] 2> 439759 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 439765 INFO (zkConnectionManagerCallback-4400-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 439765 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 439879 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 439879 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\shard-1-001\solr.xml
[junit4] 2> 439883 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@3250a117, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 440270 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 440272 WARN (closeThreadPool-4398-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@45df471d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 440272 WARN (closeThreadPool-4398-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@45df471d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 440275 WARN (closeThreadPool-4398-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@697a05dd[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 440275 WARN (closeThreadPool-4398-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@697a05dd[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 440275 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56349/solr
[junit4] 2> 440276 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 440279 INFO (zkConnectionManagerCallback-4411-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 440279 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 440392 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56484_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 440396 INFO (zkConnectionManagerCallback-4413-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 440396 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56484_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 440400 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56484_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 440406 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56484_ ] o.a.s.c.ZkController Publish node=127.0.0.1:56484_ as DOWN
[junit4] 2> 440407 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56484_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 440407 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56484_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56484_
[junit4] 2> 440408 INFO (zkCallback-4368-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 440408 INFO (zkCallback-4396-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 440408 INFO (zkCallback-4412-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 440410 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56484_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 440410 WARN (closeThreadPool-4398-thread-1) [n:127.0.0.1:56484_ ] 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> 440435 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56484_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 440454 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56484_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3250a117
[junit4] 2> 440459 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56484_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3250a117
[junit4] 2> 440460 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56484_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3250a117
[junit4] 2> 440460 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56484_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\shard-1-001\cores
[junit4] 2> 440471 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:56484_
[junit4] 2> 440628 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\shard-2-001 of type NRT for shard1
[junit4] 2> 440628 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 440628 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 440628 INFO (closeThreadPool-4398-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 15-ea+16-681
[junit4] 2> 440628 INFO (closeThreadPool-4398-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 440628 INFO (closeThreadPool-4398-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 440628 INFO (closeThreadPool-4398-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 440628 INFO (closeThreadPool-4398-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2ae10b56{/,null,AVAILABLE}
[junit4] 2> 440637 INFO (closeThreadPool-4398-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5360a5ac{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:56539}
[junit4] 2> 440637 INFO (closeThreadPool-4398-thread-1) [ ] o.e.j.s.Server Started @425458ms
[junit4] 2> 440638 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\tempDir-001/jetty2, hostPort=56539, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\shard-2-001\cores, replicaType=NRT}
[junit4] 2> 440638 ERROR (closeThreadPool-4398-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 440638 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 440638 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 440638 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 440638 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 440638 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-20T15:28:04.619546800Z
[junit4] 2> 440638 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 440645 INFO (zkConnectionManagerCallback-4423-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 440645 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 440696 INFO (OverseerCollectionConfigSetProcessor-72075615043977220-127.0.0.1:56374_-n_0000000000) [n:127.0.0.1:56374_ ] 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> 440760 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 440760 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\shard-2-001\solr.xml
[junit4] 2> 440764 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@3250a117, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 440988 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 440990 WARN (closeThreadPool-4398-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5bf3ed34[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 440990 WARN (closeThreadPool-4398-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5bf3ed34[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 440994 WARN (closeThreadPool-4398-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4ff1bd9b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 440994 WARN (closeThreadPool-4398-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4ff1bd9b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 440994 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56349/solr
[junit4] 2> 440994 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 440998 INFO (zkConnectionManagerCallback-4434-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 440998 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 441112 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56539_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 441118 INFO (zkConnectionManagerCallback-4436-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 441118 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56539_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 441125 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56539_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 441130 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56539_ ] o.a.s.c.ZkController Publish node=127.0.0.1:56539_ as DOWN
[junit4] 2> 441131 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56539_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 441131 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56539_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56539_
[junit4] 2> 441134 INFO (zkCallback-4368-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 441134 INFO (zkCallback-4412-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 441134 INFO (zkCallback-4396-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 441135 INFO (zkCallback-4435-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 441136 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56539_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 441137 WARN (closeThreadPool-4398-thread-1) [n:127.0.0.1:56539_ ] 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> 441167 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56539_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 441186 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56539_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3250a117
[junit4] 2> 441193 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56539_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3250a117
[junit4] 2> 441193 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56539_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3250a117
[junit4] 2> 441194 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56539_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\shard-2-001\cores
[junit4] 2> 441208 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:56539_
[junit4] 2> 441611 INFO (TEST-TestInPlaceUpdatesDistrib.test-seed#[A53AF3431E7CACB3]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\shard-3-001 of type NRT for shard1
[junit4] 2> 441611 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 441612 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 441612 INFO (closeThreadPool-4398-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 15-ea+16-681
[junit4] 2> 441612 INFO (closeThreadPool-4398-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 441612 INFO (closeThreadPool-4398-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 441612 INFO (closeThreadPool-4398-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 441612 INFO (closeThreadPool-4398-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@56280a62{/,null,AVAILABLE}
[junit4] 2> 441613 INFO (closeThreadPool-4398-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@df81a9d{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:56574}
[junit4] 2> 441613 INFO (closeThreadPool-4398-thread-1) [ ] o.e.j.s.Server Started @426345ms
[junit4] 2> 441613 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\tempDir-001/jetty3, hostPort=56574, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\shard-3-001\cores, replicaType=NRT}
[junit4] 2> 441614 ERROR (closeThreadPool-4398-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 441614 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 441614 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 441614 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 441614 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 441614 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-20T15:28:05.595730100Z
[junit4] 2> 441615 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 441617 INFO (zkConnectionManagerCallback-4446-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 441617 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 441730 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 441730 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\shard-3-001\solr.xml
[junit4] 2> 441734 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@3250a117, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 441774 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 441775 WARN (closeThreadPool-4398-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@345049e5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 441775 WARN (closeThreadPool-4398-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@345049e5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 441778 WARN (closeThreadPool-4398-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@330dcea[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 441778 WARN (closeThreadPool-4398-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@330dcea[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 441779 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56349/solr
[junit4] 2> 441780 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 441782 INFO (zkConnectionManagerCallback-4457-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 441782 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 441896 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56574_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 441897 INFO (zkConnectionManagerCallback-4459-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 441897 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56574_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 441902 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56574_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 441906 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56574_ ] o.a.s.c.ZkController Publish node=127.0.0.1:56574_ as DOWN
[junit4] 2> 441907 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56574_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 441907 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56574_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56574_
[junit4] 2> 441909 INFO (zkCallback-4435-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 441909 INFO (zkCallback-4396-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 441909 INFO (zkCallback-4412-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 441909 INFO (zkCallback-4368-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 441909 INFO (zkCallback-4458-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 441910 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56574_ ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 441910 WARN (closeThreadPool-4398-thread-1) [n:127.0.0.1:56574_ ] 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> 441932 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56574_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 441950 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56574_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3250a117
[junit4] 2> 441952 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56574_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3250a117
[junit4] 2> 441956 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56574_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3250a117
[junit4] 2> 441956 INFO (closeThreadPool-4398-thread-1) [n:127.0.0.1:56574_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\shard-3-001\cores
[junit4] 2> 441967 INFO (closeThreadPool-4398-thread-1) [ ] o.a.s.c.AbstractFullDistribZkTestBase waitForLiveNode: 127.0.0.1:56574_
[junit4] 2> 441971 INFO (qtp134526904-6300) [n:127.0.0.1:56374_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:56484_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 441975 INFO (qtp134526904-6297) [n:127.0.0.1:56374_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:56574_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 441976 INFO (qtp134526904-6298) [n:127.0.0.1:56374_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:56539_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 441987 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.control_collection.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 441992 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 441996 INFO (qtp2101345417-6355) [n:127.0.0.1:56484_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 442000 INFO (qtp859160477-6382) [n:127.0.0.1:56539_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 442003 INFO (qtp1389469618-6410) [n:127.0.0.1:56574_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 442005 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.control_collection.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 442005 INFO (qtp134526904-6299) [n:127.0.0.1:56374_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} st
[...truncated too long message...]
63898
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestInPlaceUpdatesDistrib -Dtests.method=test -Dtests.seed=A53AF3431E7CACB3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=ceb-PH -Dtests.timezone=Asia/Calcutta -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 93.1s J0 | TestInPlaceUpdatesDistrib.test <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.BaseHttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:64200/collection1: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001\index-NIOFSDirectory-003\_w_5_Asserting_0.dvm: Too many open files
[junit4] > at __randomizedtesting.SeedInfo.seed([A53AF3431E7CACB3:2D6ECC99B080C14B]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:663)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
[junit4] > at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
[junit4] > at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1147)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:910)
[junit4] > at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:842)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:207)
[junit4] > at org.apache.solr.client.solrj.SolrClient.commit(SolrClient.java:505)
[junit4] > at org.apache.solr.client.solrj.SolrClient.commit(SolrClient.java:480)
[junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.commit(AbstractFullDistribZkTestBase.java:1760)
[junit4] > at org.apache.solr.update.TestInPlaceUpdatesDistrib.docValuesUpdateTest(TestInPlaceUpdatesDistrib.java:468)
[junit4] > at org.apache.solr.update.TestInPlaceUpdatesDistrib.test(TestInPlaceUpdatesDistrib.java:162)
[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:564)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1081)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1053)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:832)
[junit4] 2> 110672 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 110672 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=473932079
[junit4] 2> 110673 INFO (coreCloseExecutor-188-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3f9c371f
[junit4] 2> 110673 INFO (coreCloseExecutor-188-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@3f9c371f
[junit4] 2> 110673 INFO (coreCloseExecutor-188-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@39c21850: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@3828ca65
[junit4] 2> 110682 INFO (coreCloseExecutor-188-thread-1) [ ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 110690 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 110690 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@280321d7: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@d5f6f86
[junit4] 2> 110692 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 110692 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1e1a9151: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@5174a301
[junit4] 2> 110692 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 110692 INFO (SUITE-TestInPlaceUpdatesDistrib-seed#[A53AF3431E7CACB3]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@249d6f0c: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@e74522a
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.update.TestInPlaceUpdatesDistrib_A53AF3431E7CACB3-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {_root_=PostingsFormat(name=LuceneFixedGap), title_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), id=Lucene84, id_field_copy_that_does_not_support_in_place_update_s=PostingsFormat(name=LuceneFixedGap)}, docValues:{inplace_updatable_float=DocValuesFormat(name=Asserting), id_i=DocValuesFormat(name=Lucene80), _version_=DocValuesFormat(name=Asserting), _root_=DocValuesFormat(name=Asserting), id=DocValuesFormat(name=Lucene80), inplace_updatable_int_with_default=DocValuesFormat(name=Asserting), inplace_updatable_int=DocValuesFormat(name=Lucene80), inplace_updatable_float_with_default=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1654, maxMBSortInHeap=6.577957447677907, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@7dc08f3b), locale=ceb-PH, timezone=Asia/Calcutta
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 15-ea (64-bit)/cpus=6,threads=2,free=63580448,total=274726912
[junit4] 2> NOTE: All tests run in this JVM: [TestInPlaceUpdatesDistrib]
[junit4] Completed [5/5 (5!)] on J0 in 109.79s, 1 test, 1 error <<< FAILURES!
[...truncated 17 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1521: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\common-build.xml:1048: There were test failures: 5 suites, 5 tests, 5 errors [seed: A53AF3431E7CACB3]
Total time: 1 minute 53 seconds
[repro] Setting last failure code to 1
[repro] Failures w/original seeds:
[repro] 5/5 failed: org.apache.solr.update.TestInPlaceUpdatesDistrib
[repro] Exiting with code 1
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST lucene\build move /y lucene\build lucene\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST solr\build move /y solr\build solr\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST lucene\build.orig move /y lucene\build.orig lucene\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>IF EXIST solr\build.orig move /y solr\build.orig solr\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows>exit 0
Archiving artifacts
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
No credentials specified
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=84ea0cb87dd7071648bd8efb97644f2af148fa7c, workspace=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows
[WARNINGS] Computing warning deltas based on reference build #8856
Recording test results
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
[JENKINS-Experimental-GC] Lucene-Solr-master-Windows (64bit/jdk-14)
- Build # 8858 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8858/
Java: 64bit/jdk-14 -XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseShenandoahGC
1 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger
Error Message:
ScheduledTrigger did not fire in time
Stack Trace:
java.lang.AssertionError: ScheduledTrigger did not fire in time
at __randomizedtesting.SeedInfo.seed([CBD14AC8EA7AC06B:58CA02BAB4879B5F]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:120)
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:564)
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 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:832)
Build Log:
[...truncated 14926 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[junit4] 2> 1517951 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[CBD14AC8EA7AC06B]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of 'C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\server\solr\configsets\_default\conf'
[junit4] 2> 1517953 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[CBD14AC8EA7AC06B]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_CBD14AC8EA7AC06B-001\data-dir-154-001
[junit4] 2> 1517953 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[CBD14AC8EA7AC06B]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1517955 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[CBD14AC8EA7AC06B]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 1517955 INFO (SUITE-ScheduledTriggerIntegrationTest-seed#[CBD14AC8EA7AC06B]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1517956 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testScheduledTrigger
[junit4] 2> 1517958 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_CBD14AC8EA7AC06B-001\tempDir-001
[junit4] 2> 1517958 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1517958 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1517958 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1518058 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.ZkTestServer start zk server on port: 64413
[junit4] 2> 1518058 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:64413
[junit4] 2> 1518058 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:64413
[junit4] 2> 1518058 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 64413
[junit4] 2> 1518058 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1518063 INFO (zkConnectionManagerCallback-14410-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1518064 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1518092 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1518093 INFO (zkConnectionManagerCallback-14412-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1518093 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1518095 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1518096 INFO (zkConnectionManagerCallback-14414-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1518096 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1518197 INFO (jetty-launcher-14415-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1518197 INFO (jetty-launcher-14415-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1518197 INFO (jetty-launcher-14415-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1518197 INFO (jetty-launcher-14415-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1518197 INFO (jetty-launcher-14415-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14+36
[junit4] 2> 1518197 INFO (jetty-launcher-14415-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14+36
[junit4] 2> 1518198 INFO (jetty-launcher-14415-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1518198 INFO (jetty-launcher-14415-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1518198 INFO (jetty-launcher-14415-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1518198 INFO (jetty-launcher-14415-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1518198 INFO (jetty-launcher-14415-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1518198 INFO (jetty-launcher-14415-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1518198 INFO (jetty-launcher-14415-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7a94c4a6{/solr,null,AVAILABLE}
[junit4] 2> 1518198 INFO (jetty-launcher-14415-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4d759895{/solr,null,AVAILABLE}
[junit4] 2> 1518199 INFO (jetty-launcher-14415-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6c283d2a{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:64436}
[junit4] 2> 1518199 INFO (jetty-launcher-14415-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@52d63bb4{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:64435}
[junit4] 2> 1518199 INFO (jetty-launcher-14415-thread-2) [ ] o.e.j.s.Server Started @1470648ms
[junit4] 2> 1518199 INFO (jetty-launcher-14415-thread-1) [ ] o.e.j.s.Server Started @1470648ms
[junit4] 2> 1518199 INFO (jetty-launcher-14415-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64436}
[junit4] 2> 1518199 INFO (jetty-launcher-14415-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64435}
[junit4] 2> 1518200 ERROR (jetty-launcher-14415-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1518200 ERROR (jetty-launcher-14415-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1518200 INFO (jetty-launcher-14415-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1518200 INFO (jetty-launcher-14415-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1518200 INFO (jetty-launcher-14415-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1518200 INFO (jetty-launcher-14415-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1518200 INFO (jetty-launcher-14415-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1518200 INFO (jetty-launcher-14415-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1518200 INFO (jetty-launcher-14415-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 1518200 INFO (jetty-launcher-14415-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 1518200 INFO (jetty-launcher-14415-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-20T20:53:22.283154600Z
[junit4] 2> 1518200 INFO (jetty-launcher-14415-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-20T20:53:22.283154600Z
[junit4] 2> 1518200 INFO (jetty-launcher-14415-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1518201 INFO (jetty-launcher-14415-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1518202 INFO (zkConnectionManagerCallback-14418-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1518202 INFO (jetty-launcher-14415-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1518202 INFO (zkConnectionManagerCallback-14419-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1518202 INFO (jetty-launcher-14415-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1518203 INFO (jetty-launcher-14415-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1518203 INFO (jetty-launcher-14415-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1518305 INFO (jetty-launcher-14415-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1518306 WARN (jetty-launcher-14415-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@18f9f146[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1518306 WARN (jetty-launcher-14415-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@18f9f146[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1518309 WARN (jetty-launcher-14415-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5811339e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1518309 WARN (jetty-launcher-14415-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5811339e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1518310 INFO (jetty-launcher-14415-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64413/solr
[junit4] 2> 1518310 INFO (jetty-launcher-14415-thread-2) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1518312 INFO (zkConnectionManagerCallback-14430-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1518312 INFO (jetty-launcher-14415-thread-2) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1518382 INFO (jetty-launcher-14415-thread-2) [n:127.0.0.1:64436_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:64436_solr
[junit4] 2> 1518383 INFO (jetty-launcher-14415-thread-2) [n:127.0.0.1:64436_solr ] o.a.s.c.Overseer Overseer (id=72076850644516869-127.0.0.1:64436_solr-n_0000000000) starting
[junit4] 2> 1518387 INFO (jetty-launcher-14415-thread-2) [n:127.0.0.1:64436_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64436_solr
[junit4] 2> 1518387 INFO (OverseerStateUpdate-72076850644516869-127.0.0.1:64436_solr-n_0000000000) [n:127.0.0.1:64436_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:64436_solr
[junit4] 2> 1518387 DEBUG (OverseerAutoScalingTriggerThread-72076850644516869-127.0.0.1:64436_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1518388 INFO (OverseerStateUpdate-72076850644516869-127.0.0.1:64436_solr-n_0000000000) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1518388 DEBUG (OverseerAutoScalingTriggerThread-72076850644516869-127.0.0.1:64436_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1518388 DEBUG (OverseerAutoScalingTriggerThread-72076850644516869-127.0.0.1:64436_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1518388 DEBUG (OverseerAutoScalingTriggerThread-72076850644516869-127.0.0.1:64436_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1518389 INFO (jetty-launcher-14415-thread-2) [n:127.0.0.1:64436_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1518389 WARN (jetty-launcher-14415-thread-2) [n:127.0.0.1:64436_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1518392 DEBUG (OverseerAutoScalingTriggerThread-72076850644516869-127.0.0.1:64436_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:64436_solr]
[junit4] 2> 1518392 DEBUG (OverseerAutoScalingTriggerThread-72076850644516869-127.0.0.1:64436_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1518392 DEBUG (ScheduledTrigger-14435-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 1518392 DEBUG (OverseerAutoScalingTriggerThread-72076850644516869-127.0.0.1:64436_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1518403 INFO (jetty-launcher-14415-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1518403 WARN (jetty-launcher-14415-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@47ff6d72[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1518403 WARN (jetty-launcher-14415-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@47ff6d72[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1518406 WARN (jetty-launcher-14415-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@58492ef4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1518406 WARN (jetty-launcher-14415-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@58492ef4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1518407 INFO (jetty-launcher-14415-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64413/solr
[junit4] 2> 1518408 INFO (jetty-launcher-14415-thread-1) [n:127.0.0.1:64435_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1518409 INFO (zkConnectionManagerCallback-14449-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1518409 INFO (jetty-launcher-14415-thread-1) [n:127.0.0.1:64435_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1518412 INFO (jetty-launcher-14415-thread-2) [n:127.0.0.1:64436_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1518413 INFO (jetty-launcher-14415-thread-1) [n:127.0.0.1:64435_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1518417 INFO (jetty-launcher-14415-thread-1) [n:127.0.0.1:64435_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:64435_solr as DOWN
[junit4] 2> 1518418 INFO (jetty-launcher-14415-thread-1) [n:127.0.0.1:64435_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1518418 INFO (jetty-launcher-14415-thread-1) [n:127.0.0.1:64435_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64435_solr
[junit4] 2> 1518419 INFO (zkCallback-14429-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1518419 INFO (zkCallback-14448-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1518420 INFO (jetty-launcher-14415-thread-1) [n:127.0.0.1:64435_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1518420 WARN (jetty-launcher-14415-thread-1) [n:127.0.0.1:64435_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1518432 INFO (jetty-launcher-14415-thread-2) [n:127.0.0.1:64436_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64436.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f1ebfcf
[junit4] 2> 1518437 INFO (jetty-launcher-14415-thread-2) [n:127.0.0.1:64436_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64436.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f1ebfcf
[junit4] 2> 1518437 INFO (jetty-launcher-14415-thread-2) [n:127.0.0.1:64436_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64436.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f1ebfcf
[junit4] 2> 1518438 INFO (jetty-launcher-14415-thread-2) [n:127.0.0.1:64436_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_CBD14AC8EA7AC06B-001\tempDir-001\node2\.
[junit4] 2> 1518443 INFO (jetty-launcher-14415-thread-1) [n:127.0.0.1:64435_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1518460 INFO (jetty-launcher-14415-thread-1) [n:127.0.0.1:64435_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64435.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f1ebfcf
[junit4] 2> 1518466 INFO (jetty-launcher-14415-thread-1) [n:127.0.0.1:64435_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64435.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f1ebfcf
[junit4] 2> 1518466 INFO (jetty-launcher-14415-thread-1) [n:127.0.0.1:64435_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64435.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f1ebfcf
[junit4] 2> 1518467 INFO (jetty-launcher-14415-thread-1) [n:127.0.0.1:64435_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_CBD14AC8EA7AC06B-001\tempDir-001\node1\.
[junit4] 2> 1518477 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1518478 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1518480 INFO (zkConnectionManagerCallback-14466-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1518480 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1518481 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1518482 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:64413/solr ready
[junit4] 2> 1518487 INFO (qtp1544670790-21902) [n:127.0.0.1:64435_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1518492 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 1518493 INFO (qtp1659883271-21903) [n:127.0.0.1:64436_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1518495 INFO (qtp1659883271-21903) [n:127.0.0.1:64436_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 1518495 DEBUG (qtp1544670790-21904) [n:127.0.0.1:64435_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1518496 INFO (qtp1544670790-21904) [n:127.0.0.1:64435_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=7
[junit4] 2> 1518496 DEBUG (zkCallback-14429-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 1518496 DEBUG (OverseerAutoScalingTriggerThread-72076850644516869-127.0.0.1:64436_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 1518497 INFO (qtp1659883271-21907) [n:127.0.0.1:64436_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&maxShardsPerNode=5&name=testScheduledTrigger&nrtReplicas=3&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1518498 DEBUG (OverseerAutoScalingTriggerThread-72076850644516869-127.0.0.1:64436_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1518499 DEBUG (OverseerAutoScalingTriggerThread-72076850644516869-127.0.0.1:64436_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 1518500 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testScheduledTrigger
[junit4] 2> 1518500 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1518502 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testScheduledTrigger.AUTOCREATED/protwords.txt
[junit4] 2> 1518503 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 1518505 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 1518506 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 1518507 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 1518509 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 1518510 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 1518512 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_et.txt
[junit4] 2> 1518513 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 1518514 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 1518515 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 1518517 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 1518518 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 1518519 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 1518521 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 1518523 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 1518524 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 1518526 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 1518527 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 1518528 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 1518530 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 1518531 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 1518532 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 1518534 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 1518535 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 1518537 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 1518538 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 1518539 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 1518540 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 1518542 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 1518543 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 1518545 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 1518546 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 1518547 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 1518547 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 1518547 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 1518551 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 1518553 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 1518554 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 1518555 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/testScheduledTrigger.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 1518556 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testScheduledTrigger.AUTOCREATED/solrconfig.xml
[junit4] 2> 1518558 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testScheduledTrigger.AUTOCREATED/synonyms.txt
[junit4] 2> 1518559 INFO (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testScheduledTrigger.AUTOCREATED/stopwords.txt
[junit4] 2> 1518660 WARN (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 3 on collection testScheduledTrigger is higher than the number of Solr instances currently live or live and part of your createNodeSet(2). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 1518661 DEBUG (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 1518663 INFO (qtp1659883271-21903) [n:127.0.0.1:64436_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 1518666 INFO (qtp1544670790-21902) [n:127.0.0.1:64435_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 1518666 DEBUG (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.s.c.a.PolicyHelper New session created
[junit4] 2> 1518669 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 1518671 INFO (qtp1544670790-21906) [n:127.0.0.1:64435_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=2
[junit4] 2> 1518672 DEBUG (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession, curr-time 293833222 sessionWrapper.createTime 293833216834800, this.sessionWrapper.createTime 293833216834800
[junit4] 2> 1518675 INFO (OverseerStateUpdate-72076850644516869-127.0.0.1:64436_solr-n_0000000000) [n:127.0.0.1:64436_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testScheduledTrigger",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testScheduledTrigger_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64435/solr",
[junit4] 2> "node_name":"127.0.0.1:64435_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1518677 INFO (OverseerStateUpdate-72076850644516869-127.0.0.1:64436_solr-n_0000000000) [n:127.0.0.1:64436_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testScheduledTrigger",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testScheduledTrigger_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64436/solr",
[junit4] 2> "node_name":"127.0.0.1:64436_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1518679 INFO (OverseerStateUpdate-72076850644516869-127.0.0.1:64436_solr-n_0000000000) [n:127.0.0.1:64436_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testScheduledTrigger",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testScheduledTrigger_shard1_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:64436/solr",
[junit4] 2> "node_name":"127.0.0.1:64436_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1518874 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n1&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1518874 INFO (qtp1659883271-21901) [n:127.0.0.1:64436_solr x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n2&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1518874 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n4&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1518890 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1518890 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1518890 INFO (qtp1659883271-21901) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1518905 INFO (qtp1659883271-21901) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1518907 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1518907 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 1519284 INFO (qtp1659883271-21901) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1519284 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1519284 INFO (qtp1659883271-21901) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'testScheduledTrigger_shard1_replica_n2' using configuration from configset testScheduledTrigger.AUTOCREATED, trusted=true
[junit4] 2> 1519284 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.CoreContainer Creating SolrCore 'testScheduledTrigger_shard1_replica_n4' using configuration from configset testScheduledTrigger.AUTOCREATED, trusted=true
[junit4] 2> 1519285 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64436.solr.core.testScheduledTrigger.shard1.replica_n4' (registry 'solr.core.testScheduledTrigger.shard1.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f1ebfcf
[junit4] 2> 1519285 INFO (qtp1659883271-21901) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64436.solr.core.testScheduledTrigger.shard1.replica_n2' (registry 'solr.core.testScheduledTrigger.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f1ebfcf
[junit4] 2> 1519285 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrCore [[testScheduledTrigger_shard1_replica_n4] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_CBD14AC8EA7AC06B-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n4], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_CBD14AC8EA7AC06B-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n4\data\]
[junit4] 2> 1519285 INFO (qtp1659883271-21901) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrCore [[testScheduledTrigger_shard1_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_CBD14AC8EA7AC06B-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_CBD14AC8EA7AC06B-001\tempDir-001\node2\.\testScheduledTrigger_shard1_replica_n2\data\]
[junit4] 2> 1519289 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 1519289 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testScheduledTrigger_shard1_replica_n1' using configuration from configset testScheduledTrigger.AUTOCREATED, trusted=true
[junit4] 2> 1519289 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64435.solr.core.testScheduledTrigger.shard1.replica_n1' (registry 'solr.core.testScheduledTrigger.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f1ebfcf
[junit4] 2> 1519290 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrCore [[testScheduledTrigger_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_CBD14AC8EA7AC06B-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_CBD14AC8EA7AC06B-001\tempDir-001\node1\.\testScheduledTrigger_shard1_replica_n1\data\]
[junit4] 2> 1519359 DEBUG (ScheduledTrigger-14435-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1
[junit4] 2> 1519472 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1519472 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1519474 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 1519474 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1519474 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1519474 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1519474 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1519474 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1519474 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1519479 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1519479 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1519479 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.DirectSolrSpellChecker init: {name=default, field=_text_, classname=solr.DirectSolrSpellChecker, distanceMeasure=internal, accuracy=0.5, maxEdits=2, minPrefix=1, maxInspections=5, minQueryLength=4, maxQueryFrequency=0.01}
[junit4] 2> 1519480 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 1519480 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1519480 INFO (qtp1659883271-21901) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1519480 INFO (qtp1659883271-21901) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1519480 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1519480 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1667244232344076288
[junit4] 2> 1519483 INFO (qtp1659883271-21901) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 1519483 INFO (qtp1659883271-21901) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1519483 INFO (searcherExecutor-14472-thread-1-processing-n:127.0.0.1:64435_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1519483 INFO (searcherExecutor-14472-thread-1-processing-n:127.0.0.1:64435_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1519484 INFO (searcherExecutor-14472-thread-1-processing-n:127.0.0.1:64435_solr x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1 r:core_node3) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrCore [testScheduledTrigger_shard1_replica_n1] Registered new searcher autowarm time: 0 ms: Collection: 'testScheduledTrigger'
[junit4] 2> 1519486 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1519486 INFO (qtp1659883271-21901) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1519486 INFO (qtp1659883271-21901) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1519487 INFO (qtp1659883271-21901) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1519487 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testScheduledTrigger.AUTOCREATED
[junit4] 2> 1519487 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 1519488 INFO (qtp1659883271-21901) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1519488 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1519488 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1519488 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.DirectSolrSpellChecker init: {name=default, field=_text_, classname=solr.DirectSolrSpellChecker, distanceMeasure=internal, accuracy=0.5, maxEdits=2, minPrefix=1, maxInspections=5, minQueryLength=4, maxQueryFrequency=0.01}
[junit4] 2> 1519488 INFO (qtp1659883271-21901) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 1519488 INFO (qtp1659883271-21901) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.DirectSolrSpellChecker init: {name=default, field=_text_, classname=solr.DirectSolrSpellChecker, distanceMeasure=internal, accuracy=0.5, maxEdits=2, minPrefix=1, maxInspections=5, minQueryLength=4, maxQueryFrequency=0.01}
[junit4] 2> 1519490 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testScheduledTrigger/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 1519490 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testScheduledTrigger/leaders/shard1
[junit4] 2> 1519490 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1519490 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1667244232354562048
[junit4] 2> 1519490 INFO (qtp1659883271-21901) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1519490 INFO (qtp1659883271-21901) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1667244232354562048
[junit4] 2> 1519492 INFO (searcherExecutor-14470-thread-1-processing-n:127.0.0.1:64436_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1519492 INFO (searcherExecutor-14469-thread-1-processing-n:127.0.0.1:64436_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 1519492 INFO (searcherExecutor-14470-thread-1-processing-n:127.0.0.1:64436_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1519492 INFO (searcherExecutor-14469-thread-1-processing-n:127.0.0.1:64436_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 1519493 INFO (searcherExecutor-14469-thread-1-processing-n:127.0.0.1:64436_solr x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1 r:core_node5) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrCore [testScheduledTrigger_shard1_replica_n2] Registered new searcher autowarm time: 0 ms: Collection: 'testScheduledTrigger'
[junit4] 2> 1519493 INFO (searcherExecutor-14470-thread-1-processing-n:127.0.0.1:64436_solr x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1 r:core_node6) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrCore [testScheduledTrigger_shard1_replica_n4] Registered new searcher autowarm time: 0 ms: Collection: 'testScheduledTrigger'
[junit4] 2> 1519495 INFO (qtp1659883271-21901) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testScheduledTrigger/terms/shard1 to Terms{values={core_node3=0, core_node5=0}, version=1}
[junit4] 2> 1519495 INFO (qtp1659883271-21901) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testScheduledTrigger/leaders/shard1
[junit4] 2> 1519496 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testScheduledTrigger/terms/shard1 to Terms{values={core_node6=0, core_node3=0, core_node5=0}, version=2}
[junit4] 2> 1519496 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testScheduledTrigger/leaders/shard1
[junit4] 2> 1519496 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=3 found=1 timeoute in=9999ms
[junit4] 2> 1519984 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1519984 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1519984 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:64435/solr/testScheduledTrigger_shard1_replica_n1/
[junit4] 2> 1519984 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=testScheduledTrigger_shard1_replica_n1 url=http://127.0.0.1:64435/solr START replicas=[http://127.0.0.1:64436/solr/testScheduledTrigger_shard1_replica_n2/, http://127.0.0.1:64436/solr/testScheduledTrigger_shard1_replica_n4/] nUpdates=100
[junit4] 2> 1519985 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=testScheduledTrigger_shard1_replica_n1 url=http://127.0.0.1:64435/solr DONE. We have no versions. sync failed.
[junit4] 2> 1519986 INFO (qtp1659883271-21903) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.S.Request [testScheduledTrigger_shard1_replica_n4] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1519986 INFO (qtp1659883271-21903) [n:127.0.0.1:64436_solr c:testScheduledTrigger s:shard1 r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.S.Request [testScheduledTrigger_shard1_replica_n2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 1519986 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 1519986 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 1519986 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testScheduledTrigger/leaders/shard1/leader after winning as /collections/testScheduledTrigger/leader_elect/shard1/election/72076850644516870-core_node3-n_0000000000
[junit4] 2> 1519988 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:64435/solr/testScheduledTrigger_shard1_replica_n1/ shard1
[junit4] 2> 1520086 INFO (zkCallback-14448-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1520086 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr c:testScheduledTrigger s:shard1 r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1520091 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n1&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1260
[junit4] 2> 1520185 INFO (zkCallback-14448-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1520185 INFO (zkCallback-14448-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1520347 DEBUG (ScheduledTrigger-14435-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1520468 INFO (qtp1659883271-21901) [n:127.0.0.1:64436_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n2&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1651
[junit4] 2> 1520468 INFO (qtp1659883271-21905) [n:127.0.0.1:64436_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node6&collection.configName=testScheduledTrigger.AUTOCREATED&newCollection=true&name=testScheduledTrigger_shard1_replica_n4&action=CREATE&numShards=1&collection=testScheduledTrigger&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1651
[junit4] 2> 1520468 DEBUG (OverseerThreadFactory-14437-thread-1-processing-n:127.0.0.1:64436_solr) [n:127.0.0.1:64436_solr ] o.a.s.c.s.c.a.PolicyHelper session set to NULL
[junit4] 2> 1520470 INFO (qtp1659883271-21907) [n:127.0.0.1:64436_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 1520564 INFO (zkCallback-14429-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1520564 INFO (zkCallback-14429-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1520564 INFO (zkCallback-14448-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1520564 INFO (zkCallback-14448-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testScheduledTrigger/state.json] for collection [testScheduledTrigger] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1520565 INFO (qtp1659883271-21907) [n:127.0.0.1:64436_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&maxShardsPerNode=5&name=testScheduledTrigger&nrtReplicas=3&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2} status=0 QTime=2142
[junit4] 2> 1520565 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: testScheduledTrigger
[junit4] 2> 1520568 INFO (qtp1659883271-21970) [n:127.0.0.1:64436_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testScheduledTrigger.shard1.replica_n2:INDEX.sizeInBytes&key=solr.core.testScheduledTrigger.shard1.replica_n4:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 1520570 INFO (qtp1659883271-21903) [n:127.0.0.1:64436_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1520570 INFO (qtp1544670790-21906) [n:127.0.0.1:64435_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testScheduledTrigger.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 1520571 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=1
[junit4] 2> 1520572 DEBUG (qtp1544670790-21902) [n:127.0.0.1:64435_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 1520572 INFO (qtp1544670790-21902) [n:127.0.0.1:64435_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 1520572 DEBUG (zkCallback-14429-thread-3) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 1520573 DEBUG (OverseerAutoScalingTriggerThread-72076850644516869-127.0.0.1:64436_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 1520573 DEBUG (OverseerAutoScalingTriggerThread-72076850644516869-127.0.0.1:64436_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1520573 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1520573 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1520573 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14+36
[junit4] 2> 1520573 DEBUG (OverseerAutoScalingTriggerThread-72076850644516869-127.0.0.1:64436_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 1520573 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1520573 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1520574 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1520574 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4318af13{/solr,null,AVAILABLE}
[junit4] 2> 1520575 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@506966a3{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:64483}
[junit4] 2> 1520575 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.e.j.s.Server Started @1473109ms
[junit4] 2> 1520575 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=64483}
[junit4] 2> 1520575 ERROR (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1520575 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1520576 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 1520576 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1520576 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr
[junit4] 2> 1520576 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-20T20:53:24.659295600Z
[junit4] 2> 1520576 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1520578 INFO (zkConnectionManagerCallback-14486-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1520578 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1520579 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1520681 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1520682 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2564b2d5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1520682 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2564b2d5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1520685 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@37f3533e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1520685 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@37f3533e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1520685 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:64413/solr
[junit4] 2> 1520686 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [n:127.0.0.1:64483_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1520688 INFO (zkConnectionManagerCallback-14497-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1520688 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [n:127.0.0.1:64483_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1520692 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [n:127.0.0.1:64483_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1520694 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [n:127.0.0.1:64483_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:64483_solr as DOWN
[junit4] 2> 1520695 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [n:127.0.0.1:64483_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1520695 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [n:127.0.0.1:64483_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:64483_solr
[junit4] 2> 1520697 INFO (zkCallback-14448-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1520697 INFO (zkCallback-14429-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1520697 INFO (zkCallback-14465-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1520697 INFO (zkCallback-14496-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1520698 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [n:127.0.0.1:64483_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1520698 WARN (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [n:127.0.0.1:64483_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1520720 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [n:127.0.0.1:64483_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1520738 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [n:127.0.0.1:64483_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64483.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f1ebfcf
[junit4] 2> 1520745 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [n:127.0.0.1:64483_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64483.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f1ebfcf
[junit4] 2> 1520745 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [n:127.0.0.1:64483_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_64483.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@f1ebfcf
[junit4] 2> 1520746 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [n:127.0.0.1:64483_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_CBD14AC8EA7AC06B-001\tempDir-001\node3\.
[junit4] 2> 1520756 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=3
[junit4] 2> 1520758 INFO (qtp1544670790-21906) [n:127.0.0.1:64435_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.testScheduledTrigger.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 1520761 INFO (qtp1544670790-21900) [n:127.0.0.1:64435_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metric
[...truncated too long message...]
[junit4] 2> 1560657 DEBUG (closeThreadPool-14523-thread-2) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 1560657 DEBUG (closeThreadPool-14523-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 1560657 INFO (closeThreadPool-14523-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72076850644516869-127.0.0.1:64436_solr-n_0000000000) closing
[junit4] 2> 1560742 INFO (jetty-closer-14506-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@506966a3{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1560742 INFO (jetty-closer-14506-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4318af13{/solr,null,UNAVAILABLE}
[junit4] 2> 1560743 INFO (jetty-closer-14506-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1560848 INFO (jetty-closer-14506-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72076850644516869-127.0.0.1:64436_solr-n_0000000000) closing
[junit4] 2> 1560849 INFO (jetty-closer-14506-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6c283d2a{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1560849 INFO (jetty-closer-14506-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7a94c4a6{/solr,null,UNAVAILABLE}
[junit4] 2> 1560849 INFO (jetty-closer-14506-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1560953 INFO (jetty-closer-14506-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@52d63bb4{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1560953 INFO (jetty-closer-14506-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4d759895{/solr,null,UNAVAILABLE}
[junit4] 2> 1560953 INFO (jetty-closer-14506-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1560954 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1561165 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> 6 /solr/collections/testScheduledTrigger/terms/shard1
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2> 3 /solr/configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 3 /solr/packages.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 2 /solr/configs/testScheduledTrigger.AUTOCREATED
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 20 /solr/collections/testScheduledTrigger/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 4 /solr/autoscaling.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 24 /solr/overseer/queue
[junit4] 2> 14 /solr/live_nodes
[junit4] 2> 7 /solr/collections
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 1561169 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:64413
[junit4] 2> 1561169 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:64413
[junit4] 2> 1561169 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 64413
[junit4] 2> 1563253 INFO (TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[CBD14AC8EA7AC06B]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testScheduledTrigger
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ScheduledTriggerIntegrationTest -Dtests.method=testScheduledTrigger -Dtests.seed=CBD14AC8EA7AC06B -Dtests.slow=true -Dtests.locale=ff-Latn-GN -Dtests.timezone=Europe/Bratislava -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 45.3s J1 | ScheduledTriggerIntegrationTest.testScheduledTrigger <<<
[junit4] > Throwable #1: java.lang.AssertionError: ScheduledTrigger did not fire in time
[junit4] > at __randomizedtesting.SeedInfo.seed([CBD14AC8EA7AC06B:58CA02BAB4879B5F]:0)
[junit4] > at org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:120)
[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:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:832)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_CBD14AC8EA7AC06B-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {}, docValues:{}, maxPointsInLeafNode=322, maxMBSortInHeap=7.791507663651866, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1d7a94f7), locale=ff-Latn-GN, timezone=Europe/Bratislava
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 14 (64-bit)/cpus=6,threads=1,free=236554024,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestFaceting, TestCollectionAPI, SpellCheckCollatorWithCollapseTest, TestNumericTokenStream, TestReload, TestRetrieveFieldsOptimizer, TestBinaryResponseWriter, QueryEqualityTest, TermVectorComponentTest, ExecutePlanActionTest, TestDistributedTracing, RecoveryAfterSoftCommitTest, ExternalFileFieldSortTest, TestHighFrequencyDictionaryFactory, TestAnalyzedSuggestions, DateRangeFieldTest, TestCloudDeleteByQuery, DeleteShardTest, EchoParamsTest, ConnectionReuseTest, WrapperMergePolicyFactoryTest, ZkShardTermsTest, ReplaceNodeTest, TestInitQParser, TestRandomRequestDistribution, CurrencyRangeFacetCloudTest, JWTAuthPluginTest, ConjunctionSolrSpellCheckerTest, MissingSegmentRecoveryTest, NumberUtilsTest, TestSolrCloudWithSecureImpersonation, TestObjectReleaseTracker, TestSurroundQueryParser, SpellCheckComponentTest, TestDelegationWithHadoopAuth, ZookeeperStatusHandlerTest, RandomizedTaggerTest, TestInPlaceUpdateWithRouteField, TestMergePolicyConfig, CustomCollectionTest, TestRequestStatusCollectionAPI, SliceStateTest, TestCloudManagedSchema, LeaderElectionIntegrationTest, TestBM25SimilarityFactory, TestPostingsSolrHighlighter, CategoryRoutedAliasUpdateProcessorTest, TestSchemaResource, XsltUpdateRequestHandlerTest, QueryElevationComponentTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestManagedSchema, DOMUtilTest, TestRandomDVFaceting, TestRestManager, TestDistributedSearch, MetricsHandlerTest, TestExclusionRuleCollectionAccess, TestSimExtremeIndexing, TestJavabinTupleStreamParser, IndexSizeTriggerTest, DefaultValueUpdateProcessorTest, LeaderVoteWaitTimeoutTest, SolrLogAuditLoggerPluginTest, TestSolrIndexConfig, TestLegacyFieldCache, ResourceLoaderTest, DistributedQueryComponentCustomSortTest, TestWithCollection, TestTolerantSearch, TestDocSet, DirectSolrConnectionTest, DistributedFacetPivotLongTailTest, ShowFileRequestHandlerTest, CdcrRequestHandlerTest, TestLocalStatsCacheCloud, SolrLogPostToolTest, TestFiltering, HdfsBasicDistributedZkTest, HdfsUnloadDistributedZkTest, TestHdfsBackupRestoreCore, TestPolicyCloud, TestBadConfig, TestShardHandlerFactory, TestPerFieldSimilarityWithDefaultOverride, TestSubQueryTransformer, MaxSizeAutoCommitTest, OverseerCollectionConfigSetProcessorTest, SolrTestCaseJ4Test, BasicZkTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, CloudExitableDirectoryReaderTest, ClusterStateMockUtilTest, ClusterStateTest, ClusterStateUpdateTest, CollectionPropsTest, CollectionsAPISolrJTest, MetricsHistoryIntegrationTest, MultiSolrCloudTestCaseTest, NestedShardedAtomicUpdateTest, OverseerSolrResponseTest, OverseerSolrResponseUnsafeSerializationTest, OverseerStatusTest, ReindexCollectionTest, TestLeaderElectionWithEmptyReplica, TestSkipOverseerOperations, TestTolerantUpdateProcessorCloud, ZkCLITest, NodeAddedTriggerIntegrationTest, NodeAddedTriggerTest, ScheduledTriggerIntegrationTest]
[junit4] Completed [595/913 (1!)] on J1 in 45.32s, 1 test, 1 failure <<< FAILURES!
[...truncated 45974 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8858/consoleText
[repro] Revision: 28209cb8b1fe2a4d8050e4877c4df2ad5d85509b
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseShenandoahGC"
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] ScheduledTriggerIntegrationTest
[repro] ant compile-test
[...truncated 3156 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.ScheduledTriggerIntegrationTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseShenandoahGC" -Dtests.seed=CBD14AC8EA7AC06B -Dtests.slow=true -Dtests.locale=ff-Latn-GN -Dtests.timezone=Europe/Bratislava -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 82 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[repro] Exiting with code 0
[...truncated 78 lines...]