Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/1529/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED:
org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger
Error Message:
Stack Trace:
java.lang.AssertionError
at
__randomizedtesting.SeedInfo.seed([318DAAD47B32D13C:A296E2A625CF8A08]:0)
at org.junit.Assert.fail(Assert.java:86)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.junit.Assert.assertNotNull(Assert.java:712)
at org.junit.Assert.assertNotNull(Assert.java:722)
at
org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:128)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 13357 lines...]
[junit4] Suite:
org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[junit4] 2> 43520 INFO
(SUITE-ScheduledTriggerIntegrationTest-seed#[318DAAD47B32D13C]-worker) [ ]
o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to
test-framework derived value of
'/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/server/solr/configsets/_default/conf'
[junit4] 2> 43522 INFO
(SUITE-ScheduledTriggerIntegrationTest-seed#[318DAAD47B32D13C]-worker) [ ]
o.a.s.SolrTestCaseJ4 Created dataDir:
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_318DAAD47B32D13C-001/data-dir-4-001
[junit4] 2> 43522 WARN
(SUITE-ScheduledTriggerIntegrationTest-seed#[318DAAD47B32D13C]-worker) [ ]
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=191 numCloses=191
[junit4] 2> 43522 INFO
(SUITE-ScheduledTriggerIntegrationTest-seed#[318DAAD47B32D13C]-worker) [ ]
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false)
w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 43532 INFO
(SUITE-ScheduledTriggerIntegrationTest-seed#[318DAAD47B32D13C]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
w/ MAC_OS_X supressed clientAuth
[junit4] 2> 43616 INFO
(SUITE-ScheduledTriggerIntegrationTest-seed#[318DAAD47B32D13C]-worker) [ ]
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks:
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 43642 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testScheduledTrigger
[junit4] 2> 43660 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_318DAAD47B32D13C-001/tempDir-001
[junit4] 2> 43685 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 43702 INFO (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 43702 INFO (ZkTestServer Run Thread) [ ]
o.a.s.c.ZkTestServer Starting server
[junit4] 2> 44224 WARN (ZkTestServer Run Thread) [ ]
o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 44429 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.ZkTestServer start zk server on port: 52399
[junit4] 2> 44429 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:52399
[junit4] 2> 44432 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:52399
[junit4] 2> 44433 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 52399
[junit4] 2> 45016 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 45060 WARN (NIOWorkerThread-2) [ ] o.a.z.s.NIOServerCnxn
Close of session 0x0
[junit4] 2> => java.io.IOException: ZooKeeperServer not running
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.readLength(NIOServerCnxn.java:544)
[junit4] 2> java.io.IOException: ZooKeeperServer not running
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.readLength(NIOServerCnxn.java:544)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:332)
[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory$IOWorkRequest.doWork(NIOServerCnxnFactory.java:522)
[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.server.WorkerService$ScheduledWorkRequest.run(WorkerService.java:154)
[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[?:1.8.0_201]
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[?:1.8.0_201]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_201]
[junit4] 2> 45061 WARN
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C]-SendThread(127.0.0.1:52399))
[ ] o.a.z.ClientCnxn Session 0x0 for sever localhost/127.0.0.1:52399,
Closing socket connection. Attempting reconnect except it is a
SessionExpiredException.
[junit4] 2> => EndOfStreamException: Unable to read additional
data from server sessionid 0x0, likely server has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable
to read additional data from server sessionid 0x0, likely server has closed
socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275)
[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 46756 INFO (zkConnectionManagerCallback-44-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 46757 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 46975 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 46983 INFO (zkConnectionManagerCallback-46-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 46984 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 46994 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 47016 INFO (zkConnectionManagerCallback-48-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 47017 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 47130 WARN
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C]-SendThread(127.0.0.1:52399))
[ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for
session 0x100275cbef20002.
[junit4] 2> => EndOfStreamException: Unable to read additional
data from server sessionid 0x100275cbef20002, likely server has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable
to read additional data from server sessionid 0x100275cbef20002, likely server
has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275)
[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 48701 WARN (jetty-launcher-49-thread-1) [ ]
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content,
break even is size 23
[junit4] 2> 48702 INFO (jetty-launcher-49-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 48702 INFO (jetty-launcher-49-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 48707 WARN (jetty-launcher-49-thread-2) [ ]
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content,
break even is size 23
[junit4] 2> 48707 INFO (jetty-launcher-49-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 48707 INFO (jetty-launcher-49-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 48726 INFO (jetty-launcher-49-thread-1) [ ]
o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git:
a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 48734 INFO (jetty-launcher-49-thread-2) [ ]
o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git:
a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 48818 INFO (jetty-launcher-49-thread-1) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 48818 INFO (jetty-launcher-49-thread-1) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 48815 INFO (jetty-launcher-49-thread-2) [ ]
o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 48825 INFO (jetty-launcher-49-thread-2) [ ]
o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 48832 INFO (jetty-launcher-49-thread-1) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 48835 INFO (jetty-launcher-49-thread-2) [ ]
o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 48902 INFO (jetty-launcher-49-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@290dc85c{/solr,null,AVAILABLE}
[junit4] 2> 48910 INFO (jetty-launcher-49-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1204ce36{/solr,null,AVAILABLE}
[junit4] 2> 48928 INFO (jetty-launcher-49-thread-2) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@4adbc646{SSL, (ssl,
http/1.1)}{127.0.0.1:52414}
[junit4] 2> 48928 INFO (jetty-launcher-49-thread-2) [ ]
o.e.j.s.Server Started @49017ms
[junit4] 2> 48928 INFO (jetty-launcher-49-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=52414}
[junit4] 2> 48944 INFO (jetty-launcher-49-thread-1) [ ]
o.e.j.s.AbstractConnector Started ServerConnector@24e18d8e{SSL, (ssl,
http/1.1)}{127.0.0.1:52415}
[junit4] 2> 48944 INFO (jetty-launcher-49-thread-1) [ ]
o.e.j.s.Server Started @49033ms
[junit4] 2> 48944 INFO (jetty-launcher-49-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=52415}
[junit4] 2> 49031 ERROR (jetty-launcher-49-thread-2) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 49031 INFO (jetty-launcher-49-thread-2) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 49031 INFO (jetty-launcher-49-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.7.0
[junit4] 2> 49032 INFO (jetty-launcher-49-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 49032 INFO (jetty-launcher-49-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir:
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 49032 INFO (jetty-launcher-49-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2020-09-29T00:36:26.085Z
[junit4] 2> 49034 ERROR (jetty-launcher-49-thread-1) [ ]
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 49034 INFO (jetty-launcher-49-thread-1) [ ]
o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 49034 INFO (jetty-launcher-49-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
8.7.0
[junit4] 2> 49034 INFO (jetty-launcher-49-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 49034 INFO (jetty-launcher-49-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir:
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 49034 INFO (jetty-launcher-49-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2020-09-29T00:36:26.087Z
[junit4] 2> 49036 INFO (jetty-launcher-49-thread-2) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 49039 INFO (jetty-launcher-49-thread-1) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 49046 INFO (zkConnectionManagerCallback-51-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49046 INFO (jetty-launcher-49-thread-2) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 49046 INFO (zkConnectionManagerCallback-53-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49048 INFO (jetty-launcher-49-thread-1) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 49060 INFO (jetty-launcher-49-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 49068 INFO (jetty-launcher-49-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 49101 WARN
(jetty-launcher-49-thread-2-SendThread(127.0.0.1:52399)) [ ]
o.a.z.ClientCnxn An exception was thrown while closing send thread for session
0x100275cbef20003.
[junit4] 2> => EndOfStreamException: Unable to read additional
data from server sessionid 0x100275cbef20003, likely server has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable
to read additional data from server sessionid 0x100275cbef20003, likely server
has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275)
[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 49115 WARN
(jetty-launcher-49-thread-1-SendThread(127.0.0.1:52399)) [ ]
o.a.z.ClientCnxn An exception was thrown while closing send thread for session
0x100275cbef20004.
[junit4] 2> => EndOfStreamException: Unable to read additional
data from server sessionid 0x100275cbef20004, likely server has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable
to read additional data from server sessionid 0x100275cbef20004, likely server
has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275)
[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 49421 INFO (jetty-launcher-49-thread-2) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 49425 WARN (jetty-launcher-49-thread-2) [ ]
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport
since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 49435 INFO (jetty-launcher-49-thread-1) [ ]
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized:
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 49437 WARN (jetty-launcher-49-thread-1) [ ]
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport
since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 49464 WARN (jetty-launcher-49-thread-2) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@61b9af69[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 49465 WARN (jetty-launcher-49-thread-2) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@61b9af69[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 49465 WARN (jetty-launcher-49-thread-1) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@f6952e1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 49466 WARN (jetty-launcher-49-thread-1) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@f6952e1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 49480 WARN (jetty-launcher-49-thread-2) [ ]
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport
since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 49488 WARN (jetty-launcher-49-thread-1) [ ]
o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport
since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 49503 WARN (jetty-launcher-49-thread-2) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@32043225[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 49503 WARN (jetty-launcher-49-thread-2) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@32043225[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 49504 INFO (jetty-launcher-49-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52399/solr
[junit4] 2> 49507 WARN (jetty-launcher-49-thread-1) [ ]
o.e.j.u.s.S.config Trusting all certificates configured for
Client@18d7b575[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 49507 WARN (jetty-launcher-49-thread-1) [ ]
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for
Client@18d7b575[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 49508 INFO (jetty-launcher-49-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52399/solr
[junit4] 2> 49568 INFO (jetty-launcher-49-thread-2) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 49570 INFO (jetty-launcher-49-thread-1) [ ]
o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 49572 INFO (zkConnectionManagerCallback-71-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49573 INFO (jetty-launcher-49-thread-2) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 49575 INFO (zkConnectionManagerCallback-73-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49575 INFO (jetty-launcher-49-thread-1) [ ]
o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 49579 WARN
(jetty-launcher-49-thread-2-SendThread(127.0.0.1:52399)) [ ]
o.a.z.ClientCnxn An exception was thrown while closing send thread for session
0x100275cbef20005.
[junit4] 2> => EndOfStreamException: Unable to read additional
data from server sessionid 0x100275cbef20005, likely server has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable
to read additional data from server sessionid 0x100275cbef20005, likely server
has closed socket
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350)
~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275)
[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 49765 INFO (zkConnectionManagerCallback-75-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49769 INFO (jetty-launcher-49-thread-2)
[n:127.0.0.1:52414_solr ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 49769 INFO (jetty-launcher-49-thread-2)
[n:127.0.0.1:52414_solr ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 49825 INFO (jetty-launcher-49-thread-1)
[n:127.0.0.1:52415_solr ] o.a.s.c.c.ConnectionManager Waiting for client to
connect to ZooKeeper
[junit4] 2> 49832 INFO (zkConnectionManagerCallback-77-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 49833 INFO (jetty-launcher-49-thread-1)
[n:127.0.0.1:52415_solr ] o.a.s.c.c.ConnectionManager Client is connected
to ZooKeeper
[junit4] 2> 51840 INFO (jetty-launcher-49-thread-2)
[n:127.0.0.1:52414_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:52414_solr
[junit4] 2> 51849 INFO (jetty-launcher-49-thread-2)
[n:127.0.0.1:52414_solr ] o.a.s.c.Overseer Overseer
(id=72100873331933191-127.0.0.1:52414_solr-n_0000000000) starting
[junit4] 2> 52167 INFO (jetty-launcher-49-thread-1)
[n:127.0.0.1:52415_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:52415_solr
[junit4] 2> 52284 INFO (zkCallback-76-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 52284 INFO (zkCallback-74-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 52506 INFO (jetty-launcher-49-thread-1)
[n:127.0.0.1:52415_solr ] o.a.s.p.PackageLoader /packages.json updated to
version -1
[junit4] 2> 52527 WARN (jetty-launcher-49-thread-1)
[n:127.0.0.1:52415_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> 52713 INFO
(OverseerStateUpdate-72100873331933191-127.0.0.1:52414_solr-n_0000000000)
[n:127.0.0.1:52414_solr ] o.a.s.c.Overseer Starting to work on the main
queue : 127.0.0.1:52414_solr
[junit4] 2> 52735 INFO (jetty-launcher-49-thread-2)
[n:127.0.0.1:52414_solr ] o.a.s.c.ZkController Publish
node=127.0.0.1:52414_solr as DOWN
[junit4] 2> 52762 INFO (jetty-launcher-49-thread-2)
[n:127.0.0.1:52414_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating
transient cache for 2147483647 transient cores
[junit4] 2> 52763 INFO (jetty-launcher-49-thread-2)
[n:127.0.0.1:52414_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:52414_solr
[junit4] 2> 52769 INFO (zkCallback-76-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 52769 INFO (zkCallback-74-thread-1) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 52786 INFO (jetty-launcher-49-thread-2)
[n:127.0.0.1:52414_solr ] o.a.s.p.PackageLoader /packages.json updated to
version -1
[junit4] 2> 52798 WARN (jetty-launcher-49-thread-2)
[n:127.0.0.1:52414_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> 52816 INFO (jetty-launcher-49-thread-1)
[n:127.0.0.1:52415_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 52896 INFO (jetty-launcher-49-thread-2)
[n:127.0.0.1:52414_solr ] o.a.s.h.a.MetricsHistoryHandler No .system
collection, keeping metrics history in memory.
[junit4] 2> 52909 DEBUG
(OverseerAutoScalingTriggerThread-72100873331933191-127.0.0.1:52414_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and
.scheduled_maintenance triggers
[junit4] 2> 52931 DEBUG
(OverseerAutoScalingTriggerThread-72100873331933191-127.0.0.1:52414_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with
znode version 1
[junit4] 2> 53112 INFO (jetty-launcher-49-thread-1)
[n:127.0.0.1:52415_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_52415.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7dd9814
[junit4] 2> 53113 INFO (jetty-launcher-49-thread-1)
[n:127.0.0.1:52415_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_52415.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7dd9814
[junit4] 2> 53113 INFO (jetty-launcher-49-thread-1)
[n:127.0.0.1:52415_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_52415.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7dd9814
[junit4] 2> 53118 INFO (jetty-launcher-49-thread-1)
[n:127.0.0.1:52415_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_318DAAD47B32D13C-001/tempDir-001/node1
[junit4] 2> 53119 INFO (jetty-launcher-49-thread-2)
[n:127.0.0.1:52414_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_52414.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7dd9814
[junit4] 2> 53130 INFO (jetty-launcher-49-thread-2)
[n:127.0.0.1:52414_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_52414.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7dd9814
[junit4] 2> 53130 INFO (jetty-launcher-49-thread-2)
[n:127.0.0.1:52414_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_52414.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@7dd9814
[junit4] 2> 53132 INFO (jetty-launcher-49-thread-2)
[n:127.0.0.1:52414_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_318DAAD47B32D13C-001/tempDir-001/node2
[junit4] 2> 53371 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 53376 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 53385 INFO (zkConnectionManagerCallback-102-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 53385 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 53392 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 53399 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at
127.0.0.1:52399/solr ready
[junit4] 2> 54036 DEBUG
(OverseerAutoScalingTriggerThread-72100873331933191-127.0.0.1:52414_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion 0
[junit4] 2> 54036 DEBUG
(OverseerAutoScalingTriggerThread-72100873331933191-127.0.0.1:52414_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 1
[junit4] 2> 54168 DEBUG
(OverseerAutoScalingTriggerThread-72100873331933191-127.0.0.1:52414_solr-n_0000000000)
[ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial
livenodes: [127.0.0.1:52414_solr, 127.0.0.1:52415_solr]
[junit4] 2> 54173 DEBUG
(OverseerAutoScalingTriggerThread-72100873331933191-127.0.0.1:52414_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost /
nodeAdded markers
[junit4] 2> 54179 DEBUG (ScheduledTrigger-85-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 54194 DEBUG
(OverseerAutoScalingTriggerThread-72100873331933191-127.0.0.1:52414_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1,
lastZnodeVersion 1
[junit4] 2> 55188 DEBUG (ScheduledTrigger-85-thread-3) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 55451 INFO (qtp1136585570-110) [n:127.0.0.1:52415_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=35
[junit4] 2> 55828 INFO (qtp322553927-113) [n:127.0.0.1:52414_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=35
[junit4] 2> 55854 INFO (qtp1136585570-110) [n:127.0.0.1:52415_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 55858 INFO (qtp1136585570-110) [n:127.0.0.1:52415_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=5
[junit4] 2> 55864 DEBUG (qtp1136585570-111) [n:127.0.0.1:52415_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 55871 INFO (qtp1136585570-111) [n:127.0.0.1:52415_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=198
[junit4] 2> 55873 DEBUG (zkCallback-74-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
2
[junit4] 2> 55875 DEBUG
(OverseerAutoScalingTriggerThread-72100873331933191-127.0.0.1:52414_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 2
[junit4] 2> 55914 INFO (qtp322553927-113) [n:127.0.0.1:52414_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> 55917 DEBUG
(OverseerAutoScalingTriggerThread-72100873331933191-127.0.0.1:52414_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost /
nodeAdded markers
[junit4] 2> 55919 DEBUG
(OverseerAutoScalingTriggerThread-72100873331933191-127.0.0.1:52414_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2,
lastZnodeVersion 2
[junit4] 2> 56018 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection
testScheduledTrigger
[junit4] 2> 56022 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_solr ] o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/managed-schema to
/configs/testScheduledTrigger.AUTOCREATED/managed-schema
[junit4] 2> 56030 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_solr ] o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/protwords.txt to
/configs/testScheduledTrigger.AUTOCREATED/protwords.txt
[junit4] 2> 56037 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56044 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56052 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56059 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56070 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56096 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56167 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56189 DEBUG (ScheduledTrigger-85-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 56216 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56244 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56253 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56264 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56279 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56295 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56306 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56315 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56331 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56429 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56479 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56555 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56616 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56655 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56688 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56716 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56744 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56786 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56843 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56917 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 56988 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 57072 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 57084 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 57099 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 57107 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 57115 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 57122 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 57131 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 57139 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 57157 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 57178 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 57187 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 57194 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_solr ] o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/solrconfig.xml to
/configs/testScheduledTrigger.AUTOCREATED/solrconfig.xml
[junit4] 2> 57199 DEBUG (ScheduledTrigger-85-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 57202 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_solr ] o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/synonyms.txt to
/configs/testScheduledTrigger.AUTOCREATED/synonyms.txt
[junit4] 2> 57263 INFO
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_solr ] o.a.s.c.c.ZkConfigManager Copying zk node
/configs/_default/stopwords.txt to
/configs/testScheduledTrigger.AUTOCREATED/stopwords.txt
[junit4] 2> 57509 WARN
(OverseerThreadFactory-88-thread-1-processing-n:127.0.0.1:52414_solr)
[n:127.0.0.1:52414_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> 57603 INFO
(OverseerStateUpdate-72100873331933191-127.0.0.1:52414_solr-n_0000000000)
[n:127.0.0.1:52414_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":"https://127.0.0.1:52414/solr";,
[junit4] 2> "node_name":"127.0.0.1:52414_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 57629 INFO
(OverseerStateUpdate-72100873331933191-127.0.0.1:52414_solr-n_0000000000)
[n:127.0.0.1:52414_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":"https://127.0.0.1:52415/solr";,
[junit4] 2> "node_name":"127.0.0.1:52415_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 57661 INFO
(OverseerStateUpdate-72100873331933191-127.0.0.1:52414_solr-n_0000000000)
[n:127.0.0.1:52414_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":"https://127.0.0.1:52414/solr";,
[junit4] 2> "node_name":"127.0.0.1:52414_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 58201 DEBUG (ScheduledTrigger-85-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 58532 INFO (qtp1136585570-111) [n:127.0.0.1:52415_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> 58532 INFO (qtp322553927-109) [n:127.0.0.1:52414_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> 58569 INFO (qtp322553927-108) [n:127.0.0.1:52414_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> 58750 INFO (qtp1136585570-111) [n:127.0.0.1:52415_solr
c:testScheduledTrigger s:shard1 r:core_node5
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 8.7.0
[junit4] 2> 58751 INFO (qtp322553927-108) [n:127.0.0.1:52414_solr
c:testScheduledTrigger s:shard1 r:core_node6
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 8.7.0
[junit4] 2> 58757 INFO (qtp322553927-109) [n:127.0.0.1:52414_solr
c:testScheduledTrigger s:shard1 r:core_node3
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene
MatchVersion: 8.7.0
[junit4] 2> 58810 INFO (qtp322553927-108) [n:127.0.0.1:52414_solr
c:testScheduledTrigger s:shard1 r:core_node6
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.s.IndexSchema Schema
name=default-config
[junit4] 2> 58811 INFO (qtp1136585570-111) [n:127.0.0.1:52415_solr
c:testScheduledTrigger s:shard1 r:core_node5
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema
name=default-config
[junit4] 2> 58814 INFO (qtp322553927-109) [n:127.0.0.1:52414_solr
c:testScheduledTrigger s:shard1 r:core_node3
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema
name=default-config
[junit4] 2> 59201 DEBUG (ScheduledTrigger-85-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 60206 DEBUG (ScheduledTrigger-85-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 60305 INFO (qtp322553927-108) [n:127.0.0.1:52414_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> 60305 INFO (qtp1136585570-111) [n:127.0.0.1:52415_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> 60312 INFO (qtp322553927-109) [n:127.0.0.1:52414_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> 61210 DEBUG (ScheduledTrigger-85-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 61622 INFO (qtp1136585570-111) [n:127.0.0.1:52415_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> 61625 INFO (qtp1136585570-111) [n:127.0.0.1:52415_solr
c:testScheduledTrigger s:shard1 r:core_node5
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_52415.solr.core.testScheduledTrigger.shard1.replica_n2'
(registry 'solr.core.testScheduledTrigger.shard1.replica_n2') enabled at
server: com.sun.jmx.mbeanserver.JmxMBeanServer@7dd9814
[junit4] 2> 61626 INFO (qtp1136585570-111) [n:127.0.0.1:52415_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
[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_318DAAD47B32D13C-001/tempDir-001/node1/testScheduledTrigger_shard1_replica_n2],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_318DAAD47B32D13C-001/tempDir-001/node1/testScheduledTrigger_shard1_replica_n2/data/]
[junit4] 2> 61713 INFO (qtp322553927-109) [n:127.0.0.1:52414_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> 61715 INFO (qtp322553927-109) [n:127.0.0.1:52414_solr
c:testScheduledTrigger s:shard1 r:core_node3
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_52414.solr.core.testScheduledTrigger.shard1.replica_n1'
(registry 'solr.core.testScheduledTrigger.shard1.replica_n1') enabled at
server: com.sun.jmx.mbeanserver.JmxMBeanServer@7dd9814
[junit4] 2> 61715 INFO (qtp322553927-109) [n:127.0.0.1:52414_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
[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_318DAAD47B32D13C-001/tempDir-001/node2/testScheduledTrigger_shard1_replica_n1],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_318DAAD47B32D13C-001/tempDir-001/node2/testScheduledTrigger_shard1_replica_n1/data/]
[junit4] 2> 61784 INFO (qtp322553927-108) [n:127.0.0.1:52414_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> 61785 INFO (qtp322553927-108) [n:127.0.0.1:52414_solr
c:testScheduledTrigger s:shard1 r:core_node6
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX
monitoring for 'solr_52414.solr.core.testScheduledTrigger.shard1.replica_n4'
(registry 'solr.core.testScheduledTrigger.shard1.replica_n4') enabled at
server: com.sun.jmx.mbeanserver.JmxMBeanServer@7dd9814
[junit4] 2> 61786 INFO (qtp322553927-108) [n:127.0.0.1:52414_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
[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_318DAAD47B32D13C-001/tempDir-001/node2/testScheduledTrigger_shard1_replica_n4],
dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_318DAAD47B32D13C-001/tempDir-001/node2/testScheduledTrigger_shard1_replica_n4/data/]
[junit4] 2> 62933 DEBUG (ScheduledTrigger-85-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 63465 INFO (qtp322553927-109) [n:127.0.0.1:52414_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> 63465 INFO (qtp322553927-109) [n:127.0.0.1:52414_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> 63466 INFO (qtp1136585570-111) [n:127.0.0.1:52415_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> 63467 INFO (qtp1136585570-111) [n:127.0.0.1:52415_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> 63469 INFO (qtp322553927-108) [n:127.0.0.1:52414_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> 63470 INFO (qtp322553927-108) [n:127.0.0.1:52414_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> 63484 INFO (qtp322553927-108) [n:127.0.0.1:52414_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> 63484 INFO (qtp322553927-108) [n:127.0.0.1:52414_solr
c:testScheduledTrigger s:shard1 r:core_node6
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 63484 INFO (qtp1136585570-111) [n:127.0.0.1:52415_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> 63484 INFO (qtp1136585570-111) [n:127.0.0.1:52415_solr
c:testScheduledTrigger s:shard1 r:core_node5
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 63484 INFO (qtp322553927-109) [n:127.0.0.1:52414_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> 63484 INFO (qtp322553927-109) [n:127.0.0.1:52414_solr
c:testScheduledTrigger s:shard1 r:core_node3
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 63519 INFO (qtp1136585570-111) [n:127.0.0.1:52415_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> 63520 INFO (qtp322553927-109) [n:127.0.0.1:52414_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> 63522 INFO (qtp322553927-108) [n:127.0.0.1:52414_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> 63525 INFO (qtp322553927-109) [n:127.0.0.1:52414_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> 63525 INFO (qtp1136585570-111) [n:127.0.0.1:52415_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> 63525 INFO (qtp322553927-108) [n:127.0.0.1:52414_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> 63534 INFO (qtp322553927-109) [n:127.0.0.1:52414_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> 63534 INFO (qtp1136585570-111) [n:127.0.0.1:52415_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> 63535 INFO (qtp322553927-108) [n:127.0.0.1:52414_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> 63549 INFO (qtp1136585570-111) [n:127.0.0.1:52415_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> 63549 INFO (qtp322553927-109) [n:127.0.0.1:52414_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> 63550 INFO (qtp322553927-108) [n:127.0.0.1:52414_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> 63550 INFO (qtp322553927-109) [n:127.0.0.1:52414_solr
c:testScheduledTrigger s:shard1 r:core_node3
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent
Initializing spell checkers
[junit4] 2> 63550 INFO (qtp1136585570-111) [n:127.0.0.1:52415_solr
c:testScheduledTrigger s:shard1 r:core_node5
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.h.c.SpellCheckComponent
Initializing spell checkers
[junit4] 2> 63551 INFO (qtp322553927-108) [n:127.0.0.1:52414_solr
c:testScheduledTrigger s:shard1 r:core_node6
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.h.c.SpellCheckComponent
Initializing spell checkers
[junit4] 2> 63655 INFO (qtp322553927-109) [n:127.0.0.1:52414_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> 63656 INFO (qtp322553927-108) [n:127.0.0.1:52414_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> 63657 INFO (qtp1136585570-111) [n:127.0.0.1:52415_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> 63743 INFO (qtp322553927-108) [n:127.0.0.1:52414_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> 63744 INFO (qtp322553927-109) [n:127.0.0.1:52414_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> 63748 INFO (qtp1136585570-111) [n:127.0.0.1:52415_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> 63762 INFO (qtp322553927-109) [n:127.0.0.1:52414_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 1679126482979389440
[junit4] 2> 63762 INFO (qtp1136585570-111) [n:127.0.0.1:52415_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 1679126482979389440
[junit4] 2> 63763 INFO (qtp322553927-108) [n:127.0.0.1:52414_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 1679126482980438016
[junit4] 2> 63791 INFO
(searcherExecutor-108-thread-1-processing-n:127.0.0.1:52414_solr
x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1
r:core_node6) [n:127.0.0.1:52414_solr c:testScheduledTrigger s:shard1
r:core_node6 x:testScheduledTrigger_shard1_replica_n4 ]
o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 63791 INFO
(searcherExecutor-108-thread-1-processing-n:127.0.0.1:52414_solr
x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1
r:core_node6) [n:127.0.0.1:52414_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> 63793 INFO
(searcherExecutor-104-thread-1-processing-n:127.0.0.1:52415_solr
x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1
r:core_node5) [n:127.0.0.1:52415_solr c:testScheduledTrigger s:shard1
r:core_node5 x:testScheduledTrigger_shard1_replica_n2 ]
o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 63793 INFO
(searcherExecutor-104-thread-1-processing-n:127.0.0.1:52415_solr
x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1
r:core_node5) [n:127.0.0.1:52415_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> 63796 INFO
(searcherExecutor-106-thread-1-processing-n:127.0.0.1:52414_solr
x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1
r:core_node3) [n:127.0.0.1:52414_solr c:testScheduledTrigger s:shard1
r:core_node3 x:testScheduledTrigger_shard1_replica_n1 ]
o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 63796 INFO
(searcherExecutor-106-thread-1-processing-n:127.0.0.1:52414_solr
x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1
r:core_node3) [n:127.0.0.1:52414_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> 63800 INFO
(searcherExecutor-106-thread-1-processing-n:127.0.0.1:52414_solr
x:testScheduledTrigger_shard1_replica_n1 c:testScheduledTrigger s:shard1
r:core_node3) [n:127.0.0.1:52414_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
[junit4] 2> 63800 INFO
(searcherExecutor-104-thread-1-processing-n:127.0.0.1:52415_solr
x:testScheduledTrigger_shard1_replica_n2 c:testScheduledTrigger s:shard1
r:core_node5) [n:127.0.0.1:52415_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
[junit4] 2> 63800 INFO
(searcherExecutor-108-thread-1-processing-n:127.0.0.1:52414_solr
x:testScheduledTrigger_shard1_replica_n4 c:testScheduledTrigger s:shard1
r:core_node6) [n:127.0.0.1:52414_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
[junit4] 2> 63870 INFO (qtp1136585570-111) [n:127.0.0.1:52415_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_node5=0}, version=0}
[junit4] 2> 63872 INFO (qtp322553927-108) [n:127.0.0.1:52414_solr
c:testScheduledTrigger s:shard1 r:core_node6
x:testScheduledTrigger_shard1_replica_n4 ] o.a.s.c.ZkShardTerms Failed to save
terms, version is not a match, retrying
[junit4] 2> 63880 INFO (qtp1136585570-111) [n:127.0.0.1:52415_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> 63883 INFO (qtp322553927-108) [n:127.0.0.1:52414_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_node5=0}, version=1}
[junit4] 2> 63883 INFO (qtp322553927-108) [n:127.0.0.1:52414_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> 63896 INFO (qtp322553927-109) [n:127.0.0.1:52414_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_node6=0, core_node3=0, core_node5=0}, version=2}
[junit4] 2> 63902 INFO (qtp322553927-109) [n:127.0.0.1:52414_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> 63957 INFO (qtp1136585570-111) [n:127.0.0.1:52415_solr
c:testScheduledTrigger s:shard1 r:core_node5
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext
Enough replicas found to continue.
[junit4] 2> 63957 INFO (qtp1136585570-111) [n:127.0.0.1:52415_solr
c:testScheduledTrigger s:shard1 r:core_node5
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I
may be the new leader - try and sync
[junit4] 2> 63960 INFO (qtp1136585570-111) [n:127.0.0.1:52415_solr
c:testScheduledTrigger s:shard1 r:core_node5
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas
to https://127.0.0.1:52415/solr/testScheduledTrigger_shard1_replica_n2/
[junit4] 2> 63989 INFO (qtp1136585570-111) [n:127.0.0.1:52415_solr
c:testScheduledTrigger s:shard1 r:core_node5
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync:
core=testScheduledTrigger_shard1_replica_n2 url=https://127.0.0.1:52415/solr
START
replicas=[https://127.0.0.1:52414/solr/testScheduledTrigger_shard1_replica_n1/,
https://127.0.0.1:52414/solr/testScheduledTrigger_shard1_replica_n4/]
nUpdates=100
[junit4] 2> 63996 INFO (qtp1136585570-111) [n:127.0.0.1:52415_solr
c:testScheduledTrigger s:shard1 r:core_node5
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.u.PeerSync PeerSync:
core=testScheduledTrigger_shard1_replica_n2 url=https://127.0.0.1:52415/solr
DONE. We have no versions. sync failed.
[junit4] 2> 64079 INFO (qtp322553927-193) [n:127.0.0.1:52414_solr
c:testScheduledTrigger s:shard1 r:core_node3
x:testScheduledTrigger_shard1_replica_n1 ] o.a.s.c.S.Request
[testScheduledTrigger_shard1_replica_n1] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=13
[junit4] 2> 64189 DEBUG (ScheduledTrigger-85-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 64200 INFO (qtp322553927-192) [n:127.0.0.1:52414_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=17
[junit4] 2> 64253 INFO (qtp1136585570-111) [n:127.0.0.1:52415_solr
c:testScheduledTrigger s:shard1 r:core_node5
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.SyncStrategy Leader's
attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 64253 INFO (qtp1136585570-111) [n:127.0.0.1:52415_solr
c:testScheduledTrigger s:shard1 r:core_node5
x:testScheduledTrigger_shard1_replica_n2 ] 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> 64263 INFO (qtp1136585570-111) [n:127.0.0.1:52415_solr
c:testScheduledTrigger s:shard1 r:core_node5
x:testScheduledTrigger_shard1_replica_n2 ]
o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
/collections/testScheduledTrigger/leaders/shard1/leader after winning as
/collections/testScheduledTrigger/leader_elect/shard1/election/72100873331933192-core_node5-n_0000000000
[junit4] 2> 64302 INFO (qtp1136585570-111) [n:127.0.0.1:52415_solr
c:testScheduledTrigger s:shard1 r:core_node5
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I
am the new leader:
https://127.0.0.1:52415/solr/testScheduledTrigger_shard1_replica_n2/ shard1
[junit4] 2> 64422 INFO (zkCallback-76-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> 64430 INFO (qtp1136585570-111) [n:127.0.0.1:52415_solr
c:testScheduledTrigger s:shard1 r:core_node5
x:testScheduledTrigger_shard1_replica_n2 ] o.a.s.c.ZkController I am the
leader, no recovery necessary
[junit4] 2> 64453 INFO (qtp1136585570-111) [n:127.0.0.1:52415_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=5924
[junit4] 2> 64546 INFO (zkCallback-76-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> 64546 INFO (zkCallback-76-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> 64966 INFO (qtp322553927-108) [n:127.0.0.1:52414_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=6396
[junit4] 2> 64978 INFO (qtp322553927-109) [n:127.0.0.1:52414_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=6449
[junit4] 2> 65012 INFO (qtp322553927-113) [n:127.0.0.1:52414_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> 65094 INFO (zkCallback-74-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> 65094 INFO (zkCallback-74-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> 65095 INFO (zkCallback-76-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> 65095 INFO (zkCallback-76-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> 65108 INFO (qtp322553927-113) [n:127.0.0.1:52414_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=9194
[junit4] 2> 65110 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection:
testScheduledTrigger
[junit4] 2> 65191 DEBUG (ScheduledTrigger-85-thread-1) [ ]
o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with
currently live nodes: 2 and last live nodes: 2
[junit4] 2> 65248 INFO (qtp322553927-193) [n:127.0.0.1:52414_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&key=solr.core.testScheduledTrigger.shard1.replica_n4:INDEX.sizeInBytes}
status=0 QTime=9
[junit4] 2> 65269 INFO (qtp322553927-112) [n:127.0.0.1:52414_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=2
[junit4] 2> 65284 INFO (qtp1136585570-114) [n:127.0.0.1:52415_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}
status=0 QTime=1
[junit4] 2> 65298 INFO (qtp1136585570-103) [n:127.0.0.1:52415_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> 65335 DEBUG (qtp1136585570-111) [n:127.0.0.1:52415_solr ]
o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 65345 INFO (qtp1136585570-111) [n:127.0.0.1:52415_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling
params={wt=javabin&version=2} status=0 QTime=172
[junit4] 2> 65350 DEBUG (zkCallback-74-thread-1) [ ]
o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version
3
[junit4] 2> 65351 DEBUG
(OverseerAutoScalingTriggerThread-72100873331933191-127.0.0.1:52414_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto
znodeVersion 3
[junit4] 2> 65356 DEBUG
(OverseerAutoScalingTriggerThread-72100873331933191-127.0.0.1:52414_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost /
nodeAdded markers
[junit4] 2> 65359 DEBUG
(OverseerAutoScalingTriggerThread-72100873331933191-127.0.0.1:52414_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3,
lastZnodeVersion 3
[junit4] 2> 65379 WARN
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short
content, break even is size 23
[junit4] 2> 65380 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding
port=0)
[junit4] 2> 65380 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number
2 ...
[junit4] 2> 65380 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.e.j.s.Server jetty-9.4.27.v20200227; built:
2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm
1.8.0_201-b09
[junit4] 2> 65400 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 65400 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 65400 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 65404 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@2633b48d{/solr,null,AVAILABLE}
[junit4] 2> 65413 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.e.j.s.AbstractConnector Started ServerConnector@24e6d6e7{SSL, (ssl,
http/1.1)}{127.0.0.1:52490}
[junit4] 2> 65413 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.e.j.s.Server Started @65502ms
[junit4] 2> 65413 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=52490}
[junit4] 2> 65413 ERROR
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging
may be missing or incomplete.
[junit4] 2> 65414 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.s.SolrDispatchFilter Using logger factory
org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 65414 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™
version 8.7.0
[junit4] 2> 65414 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on
port null
[junit4] 2> 65414 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir:
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
[junit4] 2> 65414 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2020-09-29T00:36:42.467Z
[junit4] 2> 65421 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 65430 INFO (zkConnectionManagerCallback-122-thread-1) [ ]
o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 65430 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 65434 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 65456 WARN (TES
[...truncated too long message...]
otName = solr_52415, domain = solr.jetty, service url = null, agent id = null]
for registry solr.jetty/com.codahale.metrics.MetricRegistry@10960c0e
[junit4] 2> 71345 INFO (jetty-closer-143-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster
tag=null
[junit4] 2> 71345 INFO (jetty-closer-143-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node
tag=null
[junit4] 2> 71345 INFO (jetty-closer-143-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6b952676: rootName =
solr_52414, domain = solr.node, service url = null, agent id = null] for
registry solr.node/com.codahale.metrics.MetricRegistry@3d0a15ff
[junit4] 2> 71366 INFO (jetty-closer-143-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm
tag=null
[junit4] 2> 71366 INFO (jetty-closer-143-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@6e589ad2: rootName =
solr_52414, domain = solr.jvm, service url = null, agent id = null] for
registry solr.jvm/com.codahale.metrics.MetricRegistry@5f12ded0
[junit4] 2> 71369 INFO (jetty-closer-143-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty
tag=null
[junit4] 2> 71369 INFO (jetty-closer-143-thread-1) [ ]
o.a.s.m.r.SolrJmxReporter Closing reporter
[org.apache.solr.metrics.reporters.SolrJmxReporter@fcf0f91: rootName =
solr_52414, domain = solr.jetty, service url = null, agent id = null] for
registry solr.jetty/com.codahale.metrics.MetricRegistry@10960c0e
[junit4] 2> 71369 INFO (jetty-closer-143-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster
tag=null
[junit4] 2> 71371 INFO (closeThreadPool-160-thread-2) [ ]
o.a.s.c.Overseer Overseer
(id=72100873331933191-127.0.0.1:52414_solr-n_0000000000) closing
[junit4] 2> 71372 INFO
(OverseerStateUpdate-72100873331933191-127.0.0.1:52414_solr-n_0000000000)
[n:127.0.0.1:52414_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:52414_solr
[junit4] 2> 71382 DEBUG (closeThreadPool-160-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 71382 INFO
(OverseerAutoScalingTriggerThread-72100873331933191-127.0.0.1:52414_solr-n_0000000000)
[ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we
are closed, exiting.
[junit4] 2> 71383 DEBUG (closeThreadPool-160-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 71383 DEBUG (closeThreadPool-160-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 71383 DEBUG (closeThreadPool-160-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool
executor
[junit4] 2> 71392 DEBUG (closeThreadPool-160-thread-2) [ ]
o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 71392 DEBUG (closeThreadPool-160-thread-2) [ ]
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 71404 INFO (closeThreadPool-160-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=72100873331933191-127.0.0.1:52414_solr-n_0000000000) closing
[junit4] 2> 71419 INFO (jetty-closer-143-thread-3) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@24e6d6e7{SSL, (ssl,
http/1.1)}{127.0.0.1:0}
[junit4] 2> 71433 INFO (jetty-closer-143-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@2633b48d{/solr,null,UNAVAILABLE}
[junit4] 2> 71433 INFO (jetty-closer-143-thread-3) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 71517 INFO (jetty-closer-143-thread-2) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@24e18d8e{SSL, (ssl,
http/1.1)}{127.0.0.1:0}
[junit4] 2> 71517 INFO (jetty-closer-143-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1204ce36{/solr,null,UNAVAILABLE}
[junit4] 2> 71518 INFO (jetty-closer-143-thread-2) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 71612 INFO (jetty-closer-143-thread-1) [ ]
o.a.s.c.Overseer Overseer
(id=72100873331933191-127.0.0.1:52414_solr-n_0000000000) closing
[junit4] 2> 71617 INFO (jetty-closer-143-thread-1) [ ]
o.e.j.s.AbstractConnector Stopped ServerConnector@4adbc646{SSL, (ssl,
http/1.1)}{127.0.0.1:0}
[junit4] 2> 71617 INFO (jetty-closer-143-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@290dc85c{/solr,null,UNAVAILABLE}
[junit4] 2> 71618 INFO (jetty-closer-143-thread-1) [ ]
o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 71621 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 71853 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> 8
/solr/collections/testScheduledTrigger/terms/shard1
[junit4] 2> 4 /solr/aliases.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> 24
/solr/collections/testScheduledTrigger/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2> 4 /solr/autoscaling.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 26 /solr/overseer/queue
[junit4] 2> 14 /solr/live_nodes
[junit4] 2> 7 /solr/collections
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2> 2
/solr/autoscaling/events/sched_trigger_integration1
[junit4] 2>
[junit4] 2> 71958 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:52399
[junit4] 2> 71958 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:52399
[junit4] 2> 71958 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 52399
[junit4] 2> 71960 INFO
(TEST-ScheduledTriggerIntegrationTest.testScheduledTrigger-seed#[318DAAD47B32D13C])
[ ] o.a.s.SolrTestCaseJ4 ###Ending testScheduledTrigger
[junit4] 2> NOTE: reproduce with: ant test
-Dtestcase=ScheduledTriggerIntegrationTest -Dtests.method=testScheduledTrigger
-Dtests.seed=318DAAD47B32D13C -Dtests.slow=true -Dtests.locale=ar-OM
-Dtests.timezone=America/Juneau -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 28.4s J2 |
ScheduledTriggerIntegrationTest.testScheduledTrigger <<<
[junit4] > Throwable #1: java.lang.AssertionError
[junit4] > at
__randomizedtesting.SeedInfo.seed([318DAAD47B32D13C:A296E2A625CF8A08]:0)
[junit4] > at
org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest.testScheduledTrigger(ScheduledTriggerIntegrationTest.java:128)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: leaving temporary files on disk at:
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.ScheduledTriggerIntegrationTest_318DAAD47B32D13C-001
[junit4] 2> Sep 29, 2020 12:36:49 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {},
docValues:{}, maxPointsInLeafNode=327, maxMBSortInHeap=7.041409394012114,
sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=ar-OM,
timezone=America/Juneau
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/Oracle Corporation 1.8.0_201
(64-bit)/cpus=6,threads=1,free=244301280,total=493879296
[junit4] 2> NOTE: All tests run in this JVM: [TestBackupRepositoryFactory,
SearchHandlerTest, TestStressReorder, ScheduledTriggerIntegrationTest]
[junit4] Completed [13/919 (1!)] on J2 in 28.82s, 1 test, 1 failure <<<
FAILURES!
[...truncated 55413 lines...]
[repro] Jenkins log URL:
https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/1529/consoleText
[repro] Revision: acce3c15b4a1dcdb69301d06e4ab9bda3d461d58
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC"
[repro] Repro line: ant test -Dtestcase=ScheduledTriggerIntegrationTest
-Dtests.method=testScheduledTrigger -Dtests.seed=318DAAD47B32D13C
-Dtests.slow=true -Dtests.locale=ar-OM -Dtests.timezone=America/Juneau
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] ScheduledTriggerIntegrationTest
[repro] ant compile-test
[...truncated 3449 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5
-Dtests.class="*.ScheduledTriggerIntegrationTest" -Dtests.showOutput=onerror
"-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC" -Dtests.seed=318DAAD47B32D13C
-Dtests.slow=true -Dtests.locale=ar-OM -Dtests.timezone=America/Juneau
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 76 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed:
org.apache.solr.cloud.autoscaling.ScheduledTriggerIntegrationTest
[repro] Exiting with code 0
[...truncated 93 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
