Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-7.x/182/

1 tests failed.
FAILED:  org.apache.solr.cloud.AliasIntegrationTest.testModifyPropertiesV1

Error Message:
Unexpected status: HTTP/1.1 400 Bad Request

Stack Trace:
java.lang.AssertionError: Unexpected status: HTTP/1.1 400 Bad Request
        at 
__randomizedtesting.SeedInfo.seed([9B1801223018C787:B8A980420D72C550]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at 
org.apache.solr.cloud.AliasIntegrationTest.assertSuccess(AliasIntegrationTest.java:320)
        at 
org.apache.solr.cloud.AliasIntegrationTest.testModifyPropertiesV1(AliasIntegrationTest.java:253)
        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:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        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:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        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 1808 lines...]
   [junit4] JVM J0: stdout was not empty, see: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/lucene/build/core/test/temp/junit4-J0-20180325_051855_1757650369699112098039.sysout
   [junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
   [junit4] codec: HighCompressionCompressingStoredFields, pf: 
TestBloomFilteredLucenePostings, dvf: Asserting
   [junit4] <<< JVM J0: EOF ----

[...truncated 11413 lines...]
   [junit4] Suite: org.apache.solr.cloud.AliasIntegrationTest
   [junit4]   2> 2428105 INFO  
(SUITE-AliasIntegrationTest-seed#[9B1801223018C787]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_9B1801223018C787-001/init-core-data-001
   [junit4]   2> 2428105 WARN  
(SUITE-AliasIntegrationTest-seed#[9B1801223018C787]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
   [junit4]   2> 2428105 INFO  
(SUITE-AliasIntegrationTest-seed#[9B1801223018C787]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 2428120 INFO  
(SUITE-AliasIntegrationTest-seed#[9B1801223018C787]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 2428121 INFO  
(SUITE-AliasIntegrationTest-seed#[9B1801223018C787]-worker) [    ] 
o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_9B1801223018C787-001/tempDir-001
   [junit4]   2> 2428121 INFO  
(SUITE-AliasIntegrationTest-seed#[9B1801223018C787]-worker) [    ] 
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2428122 INFO  (Thread-2162) [    ] o.a.s.c.ZkTestServer client 
port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2428122 INFO  (Thread-2162) [    ] o.a.s.c.ZkTestServer 
Starting server
   [junit4]   2> 2428123 ERROR (Thread-2162) [    ] o.a.z.s.ZooKeeperServer 
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action 
on ERROR or SHUTDOWN server state changes
   [junit4]   2> 2428230 INFO  
(SUITE-AliasIntegrationTest-seed#[9B1801223018C787]-worker) [    ] 
o.a.s.c.ZkTestServer start zk server on port:39869
   [junit4]   2> 2428287 INFO  (zkConnectionManagerCallback-1724-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2428326 INFO  (jetty-launcher-1721-thread-1) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-21T15:27:37-06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 2428329 INFO  (jetty-launcher-1721-thread-2) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-21T15:27:37-06:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 2428361 INFO  (jetty-launcher-1721-thread-1) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2428361 INFO  (jetty-launcher-1721-thread-1) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2428361 INFO  (jetty-launcher-1721-thread-1) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 2428362 INFO  (jetty-launcher-1721-thread-1) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@71c97123{/solr,null,AVAILABLE}
   [junit4]   2> 2428362 INFO  (jetty-launcher-1721-thread-1) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@f3ef957{SSL,[ssl, 
http/1.1]}{127.0.0.1:46429}
   [junit4]   2> 2428362 INFO  (jetty-launcher-1721-thread-1) [    ] 
o.e.j.s.Server Started @2440698ms
   [junit4]   2> 2428362 INFO  (jetty-launcher-1721-thread-1) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=46429}
   [junit4]   2> 2428362 ERROR (jetty-launcher-1721-thread-1) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2428362 INFO  (jetty-launcher-1721-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.4.0
   [junit4]   2> 2428362 INFO  (jetty-launcher-1721-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2428363 INFO  (jetty-launcher-1721-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2428363 INFO  (jetty-launcher-1721-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-03-25T08:31:10.562Z
   [junit4]   2> 2428376 INFO  (jetty-launcher-1721-thread-2) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2428376 INFO  (jetty-launcher-1721-thread-2) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2428376 INFO  (jetty-launcher-1721-thread-2) [    ] 
o.e.j.s.session Scavenging every 600000ms
   [junit4]   2> 2428377 INFO  (jetty-launcher-1721-thread-2) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@49c82b09{/solr,null,AVAILABLE}
   [junit4]   2> 2428377 INFO  (jetty-launcher-1721-thread-2) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@1ad6bd9f{SSL,[ssl, 
http/1.1]}{127.0.0.1:40307}
   [junit4]   2> 2428377 INFO  (jetty-launcher-1721-thread-2) [    ] 
o.e.j.s.Server Started @2440713ms
   [junit4]   2> 2428377 INFO  (jetty-launcher-1721-thread-2) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=40307}
   [junit4]   2> 2428377 ERROR (jetty-launcher-1721-thread-2) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2428377 INFO  (jetty-launcher-1721-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.4.0
   [junit4]   2> 2428378 INFO  (jetty-launcher-1721-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2428378 INFO  (jetty-launcher-1721-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2428378 INFO  (jetty-launcher-1721-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-03-25T08:31:10.577Z
   [junit4]   2> 2428433 INFO  (zkConnectionManagerCallback-1728-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2428433 INFO  (zkConnectionManagerCallback-1726-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2428434 INFO  (jetty-launcher-1721-thread-1) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2428434 INFO  (jetty-launcher-1721-thread-2) [    ] 
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2428491 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x101008aea5b0002, likely client has closed socket
   [junit4]   2> 2428625 INFO  (jetty-launcher-1721-thread-2) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39869/solr
   [junit4]   2> 2428657 INFO  (jetty-launcher-1721-thread-1) [    ] 
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39869/solr
   [junit4]   2> 2428660 INFO  (zkConnectionManagerCallback-1733-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2428694 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x101008aea5b0003, likely client has closed socket
   [junit4]   2> 2428714 INFO  (zkConnectionManagerCallback-1736-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2428743 INFO  
(zkConnectionManagerCallback-1738-thread-1-processing-n:127.0.0.1:40307_solr) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2428888 INFO  
(zkConnectionManagerCallback-1741-thread-1-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2429137 INFO  (jetty-launcher-1721-thread-1) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2429138 INFO  (jetty-launcher-1721-thread-1) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.OverseerElectionContext I am going to be 
the leader 127.0.0.1:46429_solr
   [junit4]   2> 2429139 INFO  (jetty-launcher-1721-thread-1) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.Overseer Overseer 
(id=72339665651957766-127.0.0.1:46429_solr-n_0000000000) starting
   [junit4]   2> 2429238 INFO  
(zkConnectionManagerCallback-1746-thread-1-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2429240 INFO  (jetty-launcher-1721-thread-1) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:39869/solr ready
   [junit4]   2> 2429311 INFO  (jetty-launcher-1721-thread-1) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:46429_solr
   [junit4]   2> 2429312 INFO  
(OverseerStateUpdate-72339665651957766-127.0.0.1:46429_solr-n_0000000000) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 2429531 INFO  
(zkCallback-1745-thread-1-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 2429569 INFO  (jetty-launcher-1721-thread-2) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (1)
   [junit4]   2> 2429605 INFO  (jetty-launcher-1721-thread-2) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.Overseer Overseer (id=null) closing
   [junit4]   2> 2429606 INFO  (jetty-launcher-1721-thread-2) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.ZkController Register node as live in 
ZooKeeper:/live_nodes/127.0.0.1:40307_solr
   [junit4]   2> 2429607 INFO  
(zkCallback-1745-thread-1-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 2429685 INFO  
(zkCallback-1737-thread-1-processing-n:127.0.0.1:40307_solr) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 2429697 INFO  
(zkCallback-1740-thread-2-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (2)
   [junit4]   2> 2430646 INFO  (jetty-launcher-1721-thread-2) 
[n:127.0.0.1:40307_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_40307.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4acc6e15
   [junit4]   2> 2430782 INFO  (jetty-launcher-1721-thread-1) 
[n:127.0.0.1:46429_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46429.solr.node' (registry 'solr.node') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4acc6e15
   [junit4]   2> 2430787 INFO  (jetty-launcher-1721-thread-2) 
[n:127.0.0.1:40307_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_40307.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4acc6e15
   [junit4]   2> 2430787 INFO  (jetty-launcher-1721-thread-2) 
[n:127.0.0.1:40307_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_40307.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4acc6e15
   [junit4]   2> 2430788 INFO  (jetty-launcher-1721-thread-2) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_9B1801223018C787-001/tempDir-001/node1/.
   [junit4]   2> 2430842 INFO  (jetty-launcher-1721-thread-1) 
[n:127.0.0.1:46429_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46429.solr.jvm' (registry 'solr.jvm') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4acc6e15
   [junit4]   2> 2430842 INFO  (jetty-launcher-1721-thread-1) 
[n:127.0.0.1:46429_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46429.solr.jetty' (registry 'solr.jetty') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4acc6e15
   [junit4]   2> 2430858 INFO  (jetty-launcher-1721-thread-1) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core 
definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_9B1801223018C787-001/tempDir-001/node2/.
   [junit4]   2> 2430872 INFO  
(zkConnectionManagerCallback-1750-thread-1-processing-n:127.0.0.1:40307_solr) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2430873 INFO  (jetty-launcher-1721-thread-2) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (0) -> (2)
   [junit4]   2> 2430874 INFO  (jetty-launcher-1721-thread-2) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster 
at 127.0.0.1:39869/solr ready
   [junit4]   2> 2431077 INFO  (zkConnectionManagerCallback-1752-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2431103 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] 
o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 
0x101008aea5b0009, likely client has closed socket
   [junit4]   2> 2431123 INFO  (zkConnectionManagerCallback-1756-thread-1) [    
] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2431129 INFO  
(SUITE-AliasIntegrationTest-seed#[9B1801223018C787]-worker) [    ] 
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2431130 INFO  
(SUITE-AliasIntegrationTest-seed#[9B1801223018C787]-worker) [    ] 
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39869/solr ready
   [junit4] IGNOR/A 0.00s J0 | AliasIntegrationTest.testProperties
   [junit4]    > Assumption #1: 'badapple' test group is disabled 
(@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
   [junit4] IGNOR/A 0.00s J0 | AliasIntegrationTest.testModifyPropertiesCAR
   [junit4]    > Assumption #1: 'badapple' test group is disabled 
(@BadApple(bugUrl=https://issues.apache.org/jira/browse/SOLR-12028))
   [junit4]   2> 2431261 INFO  
(TEST-AliasIntegrationTest.testDeleteAliasWithExistingCollectionName-seed#[9B1801223018C787])
 [    ] o.a.s.SolrTestCaseJ4 ###Starting 
testDeleteAliasWithExistingCollectionName
   [junit4]   2> 2431346 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf&name=collection_old&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2431412 INFO  
(OverseerThreadFactory-2381-thread-1-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
collection_old
   [junit4]   2> 2431612 INFO  
(OverseerStateUpdate-72339665651957766-127.0.0.1:46429_solr-n_0000000000) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"collection_old",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"collection_old_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:46429/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2431629 INFO  
(OverseerStateUpdate-72339665651957766-127.0.0.1:46429_solr-n_0000000000) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"collection_old",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"collection_old_shard2_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:40307/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2431904 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection_old_shard2_replica_n2&action=CREATE&numShards=2&collection=collection_old&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2431905 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr    
] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 
2147483647 transient cores
   [junit4]   2> 2431941 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr    ] 
o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection_old_shard1_replica_n1&action=CREATE&numShards=2&collection=collection_old&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2431942 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 2432102 INFO  
(zkCallback-1737-thread-1-processing-n:127.0.0.1:40307_solr) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection_old/state.json] for collection [collection_old] 
has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2432103 INFO  
(zkCallback-1737-thread-2-processing-n:127.0.0.1:40307_solr) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection_old/state.json] for collection [collection_old] 
has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2432106 INFO  
(zkCallback-1740-thread-2-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection_old/state.json] for collection [collection_old] 
has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2432106 INFO  
(zkCallback-1740-thread-1-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection_old/state.json] for collection [collection_old] 
has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2433042 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0
   [junit4]   2> 2433066 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0
   [junit4]   2> 2433157 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.s.IndexSchema [collection_old_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 2433157 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.s.IndexSchema [collection_old_shard2_replica_n2] Schema name=minimal
   [junit4]   2> 2433172 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 2433172 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.c.CoreContainer Creating SolrCore 'collection_old_shard2_replica_n2' 
using configuration from collection collection_old, trusted=true
   [junit4]   2> 2433173 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_40307.solr.core.collection_old.shard2.replica_n2' (registry 
'solr.core.collection_old.shard2.replica_n2') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4acc6e15
   [junit4]   2> 2433173 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2433173 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.c.SolrCore [[collection_old_shard2_replica_n2] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_9B1801223018C787-001/tempDir-001/node1/collection_old_shard2_replica_n2],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_9B1801223018C787-001/tempDir-001/node1/./collection_old_shard2_replica_n2/data/]
   [junit4]   2> 2433182 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 2433183 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.c.CoreContainer Creating SolrCore 'collection_old_shard1_replica_n1' 
using configuration from collection collection_old, trusted=true
   [junit4]   2> 2433183 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_46429.solr.core.collection_old.shard1.replica_n1' (registry 
'solr.core.collection_old.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4acc6e15
   [junit4]   2> 2433183 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2433183 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.c.SolrCore [[collection_old_shard1_replica_n1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_9B1801223018C787-001/tempDir-001/node2/collection_old_shard1_replica_n1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_9B1801223018C787-001/tempDir-001/node2/./collection_old_shard1_replica_n1/data/]
   [junit4]   2> 2433403 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2433403 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2433404 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2433405 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2433422 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@252d8873[collection_old_shard2_replica_n2] main]
   [junit4]   2> 2433423 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2433423 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2433424 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2433439 INFO  
(searcherExecutor-2386-thread-1-processing-n:127.0.0.1:40307_solr 
x:collection_old_shard2_replica_n2 s:shard2 c:collection_old r:core_node4) 
[n:127.0.0.1:40307_solr c:collection_old s:shard2 r:core_node4 
x:collection_old_shard2_replica_n2] o.a.s.c.SolrCore 
[collection_old_shard2_replica_n2] Registered new searcher 
Searcher@252d8873[collection_old_shard2_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2433439 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1595897728873791488
   [junit4]   2> 2433453 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/collection_old/terms/shard2 to Terms{values={core_node4=0}, 
version=0}
   [junit4]   2> 2433453 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2433453 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2433454 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2433454 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2433455 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7c21d486[collection_old_shard1_replica_n1] main]
   [junit4]   2> 2433456 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2433456 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2433457 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2433488 INFO  
(searcherExecutor-2387-thread-1-processing-n:127.0.0.1:46429_solr 
x:collection_old_shard1_replica_n1 s:shard1 c:collection_old r:core_node3) 
[n:127.0.0.1:46429_solr c:collection_old s:shard1 r:core_node3 
x:collection_old_shard1_replica_n1] o.a.s.c.SolrCore 
[collection_old_shard1_replica_n1] Registered new searcher 
Searcher@7c21d486[collection_old_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2433488 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1595897728925171712
   [junit4]   2> 2433490 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2433490 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2433490 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.c.SyncStrategy Sync replicas to 
https://127.0.0.1:40307/solr/collection_old_shard2_replica_n2/
   [junit4]   2> 2433490 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 2433490 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.c.SyncStrategy 
https://127.0.0.1:40307/solr/collection_old_shard2_replica_n2/ has no replicas
   [junit4]   2> 2433490 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 2433502 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:40307/solr/collection_old_shard2_replica_n2/ shard2
   [junit4]   2> 2433513 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/collection_old/terms/shard1 to Terms{values={core_node3=0}, 
version=0}
   [junit4]   2> 2433523 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2433523 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2433523 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync replicas to 
https://127.0.0.1:46429/solr/collection_old_shard1_replica_n1/
   [junit4]   2> 2433524 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 2433524 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.c.SyncStrategy 
https://127.0.0.1:46429/solr/collection_old_shard1_replica_n1/ has no replicas
   [junit4]   2> 2433524 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 2433526 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:46429/solr/collection_old_shard1_replica_n1/ shard1
   [junit4]   2> 2433627 INFO  
(zkCallback-1737-thread-1-processing-n:127.0.0.1:40307_solr) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection_old/state.json] for collection [collection_old] 
has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2433627 INFO  
(zkCallback-1740-thread-1-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection_old/state.json] for collection [collection_old] 
has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2433627 INFO  
(zkCallback-1740-thread-2-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection_old/state.json] for collection [collection_old] 
has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2433627 INFO  
(zkCallback-1737-thread-2-processing-n:127.0.0.1:40307_solr) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection_old/state.json] for collection [collection_old] 
has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2433661 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2433663 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection_old_shard2_replica_n2&action=CREATE&numShards=2&collection=collection_old&shard=shard2&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1759
   [junit4]   2> 2433677 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2433678 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection_old_shard1_replica_n1&action=CREATE&numShards=2&collection=collection_old&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1737
   [junit4]   2> 2433681 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
30 seconds. Check all shard replicas
   [junit4]   2> 2433779 INFO  
(zkCallback-1740-thread-1-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection_old/state.json] for collection [collection_old] 
has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2433779 INFO  
(zkCallback-1737-thread-1-processing-n:127.0.0.1:40307_solr) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection_old/state.json] for collection [collection_old] 
has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2433779 INFO  
(zkCallback-1737-thread-2-processing-n:127.0.0.1:40307_solr) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection_old/state.json] for collection [collection_old] 
has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2433779 INFO  
(zkCallback-1740-thread-2-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection_old/state.json] for collection [collection_old] 
has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2434689 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=1&collection.configName=conf&name=collection_old&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2}
 status=0 QTime=3343
   [junit4]   2> 2434690 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params 
replicationFactor=1&collection.configName=conf&name=collection_new&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2434693 INFO  
(OverseerThreadFactory-2381-thread-2-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.a.c.CreateCollectionCmd Create collection 
collection_new
   [junit4]   2> 2434693 INFO  
(OverseerCollectionConfigSetProcessor-72339665651957766-127.0.0.1:46429_solr-n_0000000000)
 [n:127.0.0.1:46429_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 2434902 INFO  
(OverseerStateUpdate-72339665651957766-127.0.0.1:46429_solr-n_0000000000) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"collection_new",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"collection_new_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:40307/solr";,
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 2435108 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CoreAdminOperation core create command 
qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection_new_shard1_replica_n1&action=CREATE&numShards=1&collection=collection_new&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 2435212 INFO  
(zkCallback-1737-thread-2-processing-n:127.0.0.1:40307_solr) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection_new/state.json] for collection [collection_new] 
has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2435212 INFO  
(zkCallback-1737-thread-1-processing-n:127.0.0.1:40307_solr) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection_new/state.json] for collection [collection_new] 
has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2436218 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0
   [junit4]   2> 2436248 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.s.IndexSchema [collection_new_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 2436251 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 2436251 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.c.CoreContainer Creating SolrCore 'collection_new_shard1_replica_n1' 
using configuration from collection collection_new, trusted=true
   [junit4]   2> 2436251 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr_40307.solr.core.collection_new.shard1.replica_n1' (registry 
'solr.core.collection_new.shard1.replica_n1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@4acc6e15
   [junit4]   2> 2436252 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2436252 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.c.SolrCore [[collection_new_shard1_replica_n1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_9B1801223018C787-001/tempDir-001/node1/collection_new_shard1_replica_n1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_9B1801223018C787-001/tempDir-001/node1/./collection_new_shard1_replica_n1/data/]
   [junit4]   2> 2436484 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2436484 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 2436485 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2436486 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2436487 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@50031be4[collection_new_shard1_replica_n1] main]
   [junit4]   2> 2436504 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: 
/configs/conf
   [junit4]   2> 2436505 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using 
ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 2436505 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2436507 INFO  
(searcherExecutor-2396-thread-1-processing-n:127.0.0.1:40307_solr 
x:collection_new_shard1_replica_n1 s:shard1 c:collection_new r:core_node2) 
[n:127.0.0.1:40307_solr c:collection_new s:shard1 r:core_node2 
x:collection_new_shard1_replica_n1] o.a.s.c.SolrCore 
[collection_new_shard1_replica_n1] Registered new searcher 
Searcher@50031be4[collection_new_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2436507 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1595897732090822656
   [junit4]   2> 2436511 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/collection_new/terms/shard1 to Terms{values={core_node2=0}, 
version=0}
   [junit4]   2> 2436522 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 2436522 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 2436522 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync replicas to 
https://127.0.0.1:40307/solr/collection_new_shard1_replica_n1/
   [junit4]   2> 2436522 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 2436522 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.c.SyncStrategy 
https://127.0.0.1:40307/solr/collection_new_shard1_replica_n1/ has no replicas
   [junit4]   2> 2436522 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in 
election, clear LIR
   [junit4]   2> 2436533 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.c.ShardLeaderElectionContext I am the new leader: 
https://127.0.0.1:40307/solr/collection_new_shard1_replica_n1/ shard1
   [junit4]   2> 2436635 INFO  
(zkCallback-1737-thread-1-processing-n:127.0.0.1:40307_solr) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection_new/state.json] for collection [collection_new] 
has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2436635 INFO  
(zkCallback-1737-thread-2-processing-n:127.0.0.1:40307_solr) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection_new/state.json] for collection [collection_new] 
has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2436684 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 2436695 INFO  (qtp1796788801-8175) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=conf&newCollection=true&name=collection_new_shard1_replica_n1&action=CREATE&numShards=1&collection=collection_new&shard=shard1&wt=javabin&version=2&replicaType=NRT}
 status=0 QTime=1586
   [junit4]   2> 2436700 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 
30 seconds. Check all shard replicas
   [junit4]   2> 2436787 INFO  
(zkCallback-1737-thread-2-processing-n:127.0.0.1:40307_solr) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection_new/state.json] for collection [collection_new] 
has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2436787 INFO  
(zkCallback-1737-thread-1-processing-n:127.0.0.1:40307_solr) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: 
[WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/collections/collection_new/state.json] for collection [collection_new] 
has occurred - updating... (live nodes size: [2])
   [junit4]   2> 2437700 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={replicationFactor=1&collection.configName=conf&name=collection_new&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2}
 status=0 QTime=3009
   [junit4]   2> 2437706 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/collection_old/terms/shard2 to Terms{values={core_node4=1}, 
version=1}
   [junit4]   2> 2437707 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [collection_old_shard2_replica_n2]  
webapp=/solr path=/update 
params={_stateVer_=collection_old:4&wt=javabin&version=2}{add=[6 
(1595897733347016704), 7 (1595897733348065280)]} 0 2
   [junit4]   2> 2437731 INFO  (qtp773781848-8164) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/collection_old/terms/shard1 to Terms{values={core_node3=1}, 
version=1}
   [junit4]   2> 2437732 INFO  (qtp773781848-8164) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection_old_shard1_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection_old:4&wt=javabin&version=2}{add=[8 
(1595897733373231104)]} 0 1
   [junit4]   2> 2437745 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1595897733388959744,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2437745 INFO  (qtp773781848-8161) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1595897733388959744,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2437745 INFO  (qtp773781848-8161) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@457b16cb 
commitCommandVersion:1595897733388959744
   [junit4]   2> 2437745 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@465afa01 
commitCommandVersion:1595897733388959744
   [junit4]   2> 2437759 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@18531c5[collection_old_shard2_replica_n2] main]
   [junit4]   2> 2437759 INFO  (qtp773781848-8161) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@377a472d[collection_old_shard1_replica_n1] main]
   [junit4]   2> 2437759 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2437759 INFO  (qtp773781848-8161) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2437773 INFO  
(searcherExecutor-2387-thread-1-processing-n:127.0.0.1:46429_solr 
x:collection_old_shard1_replica_n1 s:shard1 c:collection_old r:core_node3) 
[n:127.0.0.1:46429_solr c:collection_old s:shard1 r:core_node3 
x:collection_old_shard1_replica_n1] o.a.s.c.SolrCore 
[collection_old_shard1_replica_n1] Registered new searcher 
Searcher@377a472d[collection_old_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.4.0):C1)))}
   [junit4]   2> 2437774 INFO  (qtp773781848-8161) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection_old_shard1_replica_n1]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46429/solr/collection_old_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 28
   [junit4]   2> 2437774 INFO  
(searcherExecutor-2386-thread-1-processing-n:127.0.0.1:40307_solr 
x:collection_old_shard2_replica_n2 s:shard2 c:collection_old r:core_node4) 
[n:127.0.0.1:40307_solr c:collection_old s:shard2 r:core_node4 
x:collection_old_shard2_replica_n2] o.a.s.c.SolrCore 
[collection_old_shard2_replica_n2] Registered new searcher 
Searcher@18531c5[collection_old_shard2_replica_n2] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.4.0):C2)))}
   [junit4]   2> 2437774 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.u.p.LogUpdateProcessorFactory [collection_old_shard2_replica_n2]  
webapp=/solr path=/update 
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46429/solr/collection_old_shard1_replica_n1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
 0 29
   [junit4]   2> 2437787 INFO  (qtp773781848-8159) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection_old_shard1_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection_old:4&commit=true&wt=javabin&version=2}{commit=} 
0 54
   [junit4]   2> 2437796 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/collection_new/terms/shard1 to Terms{values={core_node2=1}, 
version=1}
   [junit4]   2> 2437796 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection_new_shard1_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection_new:4&wt=javabin&version=2}{add=[1 
(1595897733440339968)]} 0 2
   [junit4]   2> 2437796 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1595897733442437120,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2437805 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3d1b8750 
commitCommandVersion:1595897733442437120
   [junit4]   2> 2437808 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7e6d8a93[collection_new_shard1_replica_n1] main]
   [junit4]   2> 2437817 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2437818 INFO  
(searcherExecutor-2396-thread-1-processing-n:127.0.0.1:40307_solr 
x:collection_new_shard1_replica_n1 s:shard1 c:collection_new r:core_node2) 
[n:127.0.0.1:40307_solr c:collection_new s:shard1 r:core_node2 
x:collection_new_shard1_replica_n1] o.a.s.c.SolrCore 
[collection_new_shard1_replica_n1] Registered new searcher 
Searcher@7e6d8a93[collection_new_shard1_replica_n1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.4.0):C1)))}
   [junit4]   2> 2437818 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection_new_shard1_replica_n1]  
webapp=/solr path=/update 
params={_stateVer_=collection_new:4&commit=true&wt=javabin&version=2}{commit=} 
0 21
   [junit4]   2> 2437828 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.c.S.Request [collection_old_shard2_replica_n2]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection_old:4&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=https://127.0.0.1:40307/solr/collection_old_shard2_replica_n2/&rows=10&version=2&q=*:*&NOW=1521966680026&isShard=true&wt=javabin}
 hits=2 status=0 QTime=0
   [junit4]   2> 2437829 INFO  (qtp773781848-8162) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.c.S.Request [collection_old_shard1_replica_n1]  webapp=/solr path=/select 
params={df=text&distrib=false&_stateVer_=collection_old:4&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=https://127.0.0.1:46429/solr/collection_old_shard1_replica_n1/&rows=10&version=2&q=*:*&NOW=1521966680026&isShard=true&wt=javabin}
 hits=1 status=0 QTime=0
   [junit4]   2> 2437846 INFO  (qtp773781848-8161) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.c.S.Request [collection_old_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&df=text&distrib=false&_stateVer_=collection_old:4&shards.purpose=64&NOW=1521966680026&ids=8&isShard=true&shard.url=https://127.0.0.1:46429/solr/collection_old_shard1_replica_n1/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2437846 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr 
c:collection_old s:shard2 r:core_node4 x:collection_old_shard2_replica_n2] 
o.a.s.c.S.Request [collection_old_shard2_replica_n2]  webapp=/solr path=/select 
params={q=*:*&df=text&distrib=false&_stateVer_=collection_old:4&shards.purpose=64&NOW=1521966680026&ids=6,7&isShard=true&shard.url=https://127.0.0.1:40307/solr/collection_old_shard2_replica_n2/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2437847 INFO  (qtp773781848-8164) [n:127.0.0.1:46429_solr 
c:collection_old s:shard1 r:core_node3 x:collection_old_shard1_replica_n1] 
o.a.s.c.S.Request [collection_old_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection_old:4&wt=javabin&version=2} hits=3 status=0 
QTime=20
   [junit4]   2> 2437848 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :createalias with 
params 
collections=collection_old&name=collection_old_reserve&action=CREATEALIAS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2437988 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={collections=collection_old&name=collection_old_reserve&action=CREATEALIAS&wt=javabin&version=2}
 status=0 QTime=139
   [junit4]   2> 2437989 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :createalias with 
params 
collections=collection_new&name=collection_old&action=CREATEALIAS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2437994 INFO  
(OverseerCollectionConfigSetProcessor-72339665651957766-127.0.0.1:46429_solr-n_0000000000)
 [n:127.0.0.1:46429_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000004 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 2438122 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={collections=collection_new&name=collection_old&action=CREATEALIAS&wt=javabin&version=2}
 status=0 QTime=132
   [junit4]   2> 2438123 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.c.S.Request [collection_new_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection_old:4&wt=javabin&version=2} hits=1 status=0 
QTime=0
   [junit4]   2> 2438124 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.c.S.Request [collection_new_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection_new:4&wt=javabin&version=2} hits=1 status=0 
QTime=0
   [junit4]   2> 2438125 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params 
async=0f7b6e90-fca1-43ee-ad3f-ac9ac54cf2a1&name=collection_old&action=DELETE&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2438135 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={async=0f7b6e90-fca1-43ee-ad3f-ac9ac54cf2a1&name=collection_old&action=DELETE&wt=javabin&version=2}
 status=0 QTime=10
   [junit4]   2> 2438135 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=0f7b6e90-fca1-43ee-ad3f-ac9ac54cf2a1&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2438136 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=0f7b6e90-fca1-43ee-ad3f-ac9ac54cf2a1&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 2438137 INFO  
(OverseerCollectionConfigSetProcessor-72339665651957766-127.0.0.1:46429_solr-n_0000000000)
 [n:127.0.0.1:46429_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000006 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 2438137 ERROR 
(OverseerThreadFactory-2381-thread-5-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.a.c.OverseerCollectionMessageHandler 
Collection: collection_old operation: delete 
failed:org.apache.solr.common.SolrException: Collection : collection_old is 
part of alias collection_old_reserve remove or modify the alias before removing 
this collection.
   [junit4]   2>        at 
org.apache.solr.cloud.api.collections.DeleteCollectionCmd.call(DeleteCollectionCmd.java:71)
   [junit4]   2>        at 
org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:252)
   [junit4]   2>        at 
org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:469)
   [junit4]   2>        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
   [junit4]   2>        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 2438200 INFO  
(OverseerThreadFactory-2381-thread-5-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000008 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 2439143 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=0f7b6e90-fca1-43ee-ad3f-ac9ac54cf2a1&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2439145 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=0f7b6e90-fca1-43ee-ad3f-ac9ac54cf2a1&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 2439145 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletestatus with 
params 
requestid=0f7b6e90-fca1-43ee-ad3f-ac9ac54cf2a1&action=DELETESTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2439146 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=0f7b6e90-fca1-43ee-ad3f-ac9ac54cf2a1&action=DELETESTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2439155 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr 
c:collection_new s:shard1 r:core_node2 x:collection_new_shard1_replica_n1] 
o.a.s.c.S.Request [collection_new_shard1_replica_n1]  webapp=/solr path=/select 
params={q=*:*&_stateVer_=collection_old:4&wt=javabin&version=2} hits=1 status=0 
QTime=0
   [junit4]   2> 2439157 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletealias with 
params 
async=5d186bda-cb15-4f2d-bf70-f57cebd6b2bf&name=collection_old_reserve&action=DELETEALIAS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2439159 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={async=5d186bda-cb15-4f2d-bf70-f57cebd6b2bf&name=collection_old_reserve&action=DELETEALIAS&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 2439159 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=5d186bda-cb15-4f2d-bf70-f57cebd6b2bf&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2439162 INFO  
(OverseerCollectionConfigSetProcessor-72339665651957766-127.0.0.1:46429_solr-n_0000000000)
 [n:127.0.0.1:46429_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000008 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 2439162 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=5d186bda-cb15-4f2d-bf70-f57cebd6b2bf&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=2
   [junit4]   2> 2439176 INFO  
(OverseerThreadFactory-2381-thread-5-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000009 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 2440163 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=5d186bda-cb15-4f2d-bf70-f57cebd6b2bf&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2440164 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=5d186bda-cb15-4f2d-bf70-f57cebd6b2bf&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 2440165 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletestatus with 
params 
requestid=5d186bda-cb15-4f2d-bf70-f57cebd6b2bf&action=DELETESTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2440165 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=5d186bda-cb15-4f2d-bf70-f57cebd6b2bf&action=DELETESTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2440173 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletealias with 
params 
async=47f346c3-76bf-40c2-8006-989dbd621222&name=collection_old&action=DELETEALIAS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2440175 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={async=47f346c3-76bf-40c2-8006-989dbd621222&name=collection_old&action=DELETEALIAS&wt=javabin&version=2}
 status=0 QTime=2
   [junit4]   2> 2440176 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=47f346c3-76bf-40c2-8006-989dbd621222&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2440178 INFO  
(OverseerCollectionConfigSetProcessor-72339665651957766-127.0.0.1:46429_solr-n_0000000000)
 [n:127.0.0.1:46429_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000009 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 2440179 INFO  
(OverseerThreadFactory-2381-thread-5-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000010 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 2440186 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=47f346c3-76bf-40c2-8006-989dbd621222&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=10
   [junit4]   2> 2441192 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=47f346c3-76bf-40c2-8006-989dbd621222&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2441193 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=47f346c3-76bf-40c2-8006-989dbd621222&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 2441193 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletestatus with 
params 
requestid=47f346c3-76bf-40c2-8006-989dbd621222&action=DELETESTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2441194 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=47f346c3-76bf-40c2-8006-989dbd621222&action=DELETESTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2441194 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params 
async=15eaff13-8c70-4187-978a-9da15086bf57&name=collection_new&action=DELETE&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2441197 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={async=15eaff13-8c70-4187-978a-9da15086bf57&name=collection_new&action=DELETE&wt=javabin&version=2}
 status=0 QTime=2
   [junit4]   2> 2441198 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=15eaff13-8c70-4187-978a-9da15086bf57&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2441207 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=15eaff13-8c70-4187-978a-9da15086bf57&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=9
   [junit4]   2> 2441216 INFO  
(OverseerCollectionConfigSetProcessor-72339665651957766-127.0.0.1:46429_solr-n_0000000000)
 [n:127.0.0.1:46429_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000010 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 2441216 INFO  
(OverseerThreadFactory-2381-thread-5-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.a.c.OverseerCollectionMessageHandler 
Executing Collection 
Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true, 
asyncId=15eaff13-8c70-4187-978a-9da15086bf57
   [junit4]   2> 2441237 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=collection_new_shard1_replica_n1&async=15eaff13-8c70-4187-978a-9da15086bf574304084354049512&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=18
   [junit4]   2> 2441238 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CoreAdminOperation Checking request status for : 
15eaff13-8c70-4187-978a-9da15086bf574304084354049512
   [junit4]   2> 2441238 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=15eaff13-8c70-4187-978a-9da15086bf574304084354049512&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2441239 INFO  
(parallelCoreAdminExecutor-2382-thread-1-processing-n:127.0.0.1:40307_solr 
15eaff13-8c70-4187-978a-9da15086bf574304084354049512 UNLOAD) 
[n:127.0.0.1:40307_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters 
for registry=solr.core.collection_new.shard1.replica_n1, tag=null
   [junit4]   2> 2441239 INFO  
(parallelCoreAdminExecutor-2382-thread-1-processing-n:127.0.0.1:40307_solr 
15eaff13-8c70-4187-978a-9da15086bf574304084354049512 UNLOAD) 
[n:127.0.0.1:40307_solr    ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@39ee3813: rootName = 
solr_40307, domain = solr.core.collection_new.shard1.replica_n1, service url = 
null, agent id = null] for registry solr.core.collection_new.shard1.replica_n1 
/ com.codahale.metrics.MetricRegistry@2afc9330
   [junit4]   2> 2441271 INFO  
(parallelCoreAdminExecutor-2382-thread-1-processing-n:127.0.0.1:40307_solr 
15eaff13-8c70-4187-978a-9da15086bf574304084354049512 UNLOAD) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.SolrCore 
[collection_new_shard1_replica_n1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@7f726c83
   [junit4]   2> 2441272 INFO  
(parallelCoreAdminExecutor-2382-thread-1-processing-n:127.0.0.1:40307_solr 
15eaff13-8c70-4187-978a-9da15086bf574304084354049512 UNLOAD) 
[n:127.0.0.1:40307_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters 
for registry=solr.core.collection_new.shard1.replica_n1, tag=2138205315
   [junit4]   2> 2441272 INFO  
(parallelCoreAdminExecutor-2382-thread-1-processing-n:127.0.0.1:40307_solr 
15eaff13-8c70-4187-978a-9da15086bf574304084354049512 UNLOAD) 
[n:127.0.0.1:40307_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters 
for registry=solr.collection.collection_new.shard1.leader, tag=2138205315
   [junit4]   2> 2441316 INFO  
(parallelCoreAdminExecutor-2382-thread-1-processing-n:127.0.0.1:40307_solr 
15eaff13-8c70-4187-978a-9da15086bf574304084354049512 UNLOAD) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/collection_new/terms/shard1 to Terms{values={}, version=2}
   [junit4]   2> 2442210 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=15eaff13-8c70-4187-978a-9da15086bf57&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2442211 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=15eaff13-8c70-4187-978a-9da15086bf57&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 2442239 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CoreAdminOperation Checking request status for : 
15eaff13-8c70-4187-978a-9da15086bf574304084354049512
   [junit4]   2> 2442239 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=15eaff13-8c70-4187-978a-9da15086bf574304084354049512&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2442986 INFO  
(OverseerThreadFactory-2381-thread-5-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000011 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 2443217 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=15eaff13-8c70-4187-978a-9da15086bf57&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2443222 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=15eaff13-8c70-4187-978a-9da15086bf57&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=4
   [junit4]   2> 2443222 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletestatus with 
params 
requestid=15eaff13-8c70-4187-978a-9da15086bf57&action=DELETESTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2443223 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=15eaff13-8c70-4187-978a-9da15086bf57&action=DELETESTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2443224 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params 
async=7190c3e1-6605-4a46-bd62-d40e5cd6f2a3&name=collection_old&action=DELETE&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2443254 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={async=7190c3e1-6605-4a46-bd62-d40e5cd6f2a3&name=collection_old&action=DELETE&wt=javabin&version=2}
 status=0 QTime=30
   [junit4]   2> 2443254 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params 
requestid=7190c3e1-6605-4a46-bd62-d40e5cd6f2a3&action=REQUESTSTATUS&wt=javabin&version=2
 and sendToOCPQueue=true
   [junit4]   2> 2443256 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections 
params={requestid=7190c3e1-6605-4a46-bd62-d40e5cd6f2a3&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=1
   [junit4]   2> 2443261 INFO  
(OverseerCollectionConfigSetProcessor-72339665651957766-127.0.0.1:46429_solr-n_0000000000)
 [n:127.0.0.1:46429_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: 
/overseer/collection-queue-work/qnr-0000000011 doesn't exist.  Requestor may 
have disconnected from ZooKeeper
   [junit4]   2> 2443315 INFO  
(OverseerThreadFactory-2381-thread-5-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.a.c.OverseerCollectionMessageHandler 
Executing Collection 
Cmd=action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true, 
asyncId=7190c3e1-6605-4a46-bd62-d40e5cd6f2a3
   [junit4]   2> 2443399 INFO  (qtp1796788801-8172) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=collection_old_shard2_replica_n2&async=7190c3e1-6605-4a46-bd62-d40e5cd6f2a34304086452113666&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=33
   [junit4]   2> 2443400 INFO  
(parallelCoreAdminExecutor-2382-thread-2-processing-n:127.0.0.1:40307_solr 
7190c3e1-6605-4a46-bd62-d40e5cd6f2a34304086452113666 UNLOAD) 
[n:127.0.0.1:40307_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters 
for registry=solr.core.collection_old.shard2.replica_n2, tag=null
   [junit4]   2> 2443400 INFO  
(parallelCoreAdminExecutor-2382-thread-2-processing-n:127.0.0.1:40307_solr 
7190c3e1-6605-4a46-bd62-d40e5cd6f2a34304086452113666 UNLOAD) 
[n:127.0.0.1:40307_solr    ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@1cea8492: rootName = 
solr_40307, domain = solr.core.collection_old.shard2.replica_n2, service url = 
null, agent id = null] for registry solr.core.collection_old.shard2.replica_n2 
/ com.codahale.metrics.MetricRegistry@6822b12d
   [junit4]   2> 2443432 INFO  (qtp773781848-8162) [n:127.0.0.1:46429_solr    ] 
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={deleteInstanceDir=true&core=collection_old_shard1_replica_n1&async=7190c3e1-6605-4a46-bd62-d40e5cd6f2a34304086452029628&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
 status=0 QTime=65
   [junit4]   2> 2443457 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CoreAdminOperation Checking request status for : 
7190c3e1-6605-4a46-bd62-d40e5cd6f2a34304086452113666
   [junit4]   2> 2443457 INFO  (qtp1796788801-8169) [n:127.0.0.1:40307_solr    
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores 
params={qt=/admin/cores&requestid=7190c3e1-6605-4a46-bd62-d40e5cd6f2a34304086452113666&action=REQUESTSTATUS&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2443462 INFO  
(parallelCoreAdminExecutor-2382-thread-2-processing-n:127.0.0.1:40307_solr 
7190c3e1-6605-4a46-bd62-d40e5cd6f2a34304086452113666 UNLOAD) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.SolrCore 
[collection_old_shard2_replica_n2]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@62bd4fca
   [junit4]   2> 2443462 INFO  
(parallelCoreAdminExecutor-2382-thread-2-processing-n:127.0.0.1:40307_solr 
7190c3e1-6605-4a46-bd62-d40e5cd6f2a34304086452113666 UNLOAD) 
[n:127.0.0.1:40307_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters 
for registry=solr.core.collection_old.shard2.replica_n2, tag=1656573898
   [junit4]   2> 2443462 INFO  
(parallelCoreAdminExecutor-2382-thread-2-processing-n:127.0.0.1:40307_solr 
7190c3e1-6605-4a46-bd62-d40e5cd6f2a34304086452113666 UNLOAD) 
[n:127.0.0.1:40307_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters 
for registry=solr.collection.collection_old.shard2.leader, tag=1656573898
   [junit4]   2> 2443482 INFO  
(parallelCoreAdminExecutor-2383-thread-1-processing-n:127.0.0.1:46429_solr 
7190c3e1-6605-4a46-bd62-d40e5cd6f2a34304086452029628 UNLOAD) 
[n:127.0.0.1:46429_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters 
for registry=solr.core.collection_old.shard1.replica_n1, tag=null
   [junit4]   2> 2443482 INFO  
(parallelCoreAdminExecutor-2383-thread-1-processing-n:127.0.0.1:46429_solr 
7190c3e1-6605-4a46-bd62-d40e5cd6f2a34304086452029628 UNLOAD) 
[n:127.0.0.1:46429_solr    ] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@4acbf3d6: rootName = 
solr_46429, domain = solr.core.collection_old.shard1.replica_n1, service url = 
null, agent id = null] for registry solr.core.collection_old.shard1.replica_n1 
/ com.codahale.metrics.MetricRegistry@25bab4cd
   [junit4]   2> 2443519 INFO  
(parallelCoreAdminExecutor-2382-thread-2-processing-n:127.0.0.1:40307_solr 
7190c3e1-6605-4a46-bd62-d40e5cd6f2a34304086452113666 UNLOAD) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/collection_old/terms/shard2 to Terms{values={}, version=2}
   [junit4]   2> 2443540 INFO  
(parallelCoreAdminExecutor-2383-thread-1-processing-n:127.0.0.1:46429_solr 
7190c3e1-6605-4a46-bd62-d40e5cd6f2a34304086452029628 UNLOAD) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.SolrCore 
[collection_old_shard1_replica_n1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@4041af83
   [junit4]   2> 2443540 INFO  
(parallelCoreAdminExecutor-2383-thread-1-processing-n:127.0.0.1:46429_solr 
7190c3e1-6605-4a46-bd62-d40e5cd6f2a34304086452029628 UNLOAD) 
[n:127.0.0.1:46429_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters 
for registry=solr.core.collection_old.shard1.replica_n1, tag=1078046595
   [junit4]   2> 2443540 INFO  
(parallelCoreAdminExecutor-2383-thread-1-processing-n:127.0.0.1:46429_solr 
7190c3e1-6605-4a46-bd62-d40e5cd6f2a34304086452029628 UNLOAD) 
[n:127.0.0.1:46429_solr    ] o.a.s.m.SolrMetricManager Closing metric reporters 
for registry=solr.collection.collection_old.shard1.leader, tag=1078046595
   [junit4]   2> 2443591 INFO  
(parallelCoreAdminExecutor-2383-thread-1-processing-n:127.0.0.1:46429_solr 
7190c3e1-6605-4a46-bd62-d40e5cd6f2a34304086452029628 UNLOAD) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.ZkShardTerms Successful update of terms at 
/collections/collection_old/terms/shard1 to Terms{values={}, version=2}
   [junit4]   2> 2444269 INFO  (qtp1796788801-8174) [n:127.0.0.1:40307_solr    
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with 
params requestid=7190c3e1-6605-4a46-bd62-d40e5cd6f2a3&action=REQUE

[...truncated too long message...]

reporters for registry=solr.node, tag=null
   [junit4]   2> 2499027 INFO  (jetty-closer-1722-thread-2) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@7cfc18b5: rootName = 
solr_46429, domain = solr.node, service url = null, agent id = null] for 
registry solr.node / com.codahale.metrics.MetricRegistry@6be9051e
   [junit4]   2> 2499027 INFO  (jetty-closer-1722-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 2499027 INFO  (jetty-closer-1722-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@586b03f6: rootName = 
solr_40307, domain = solr.jetty, service url = null, agent id = null] for 
registry solr.jetty / com.codahale.metrics.MetricRegistry@75d6302f
   [junit4]   2> 2499032 INFO  (jetty-closer-1722-thread-2) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 2499032 INFO  (jetty-closer-1722-thread-2) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@7a046d8f: rootName = 
solr_46429, domain = solr.jvm, service url = null, agent id = null] for 
registry solr.jvm / com.codahale.metrics.MetricRegistry@68d41d93
   [junit4]   2> 2499056 INFO  (jetty-closer-1722-thread-2) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 2499056 INFO  (jetty-closer-1722-thread-2) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@220abac3: rootName = 
solr_46429, domain = solr.jetty, service url = null, agent id = null] for 
registry solr.jetty / com.codahale.metrics.MetricRegistry@75d6302f
   [junit4]   2> 2499089 INFO  (jetty-closer-1722-thread-1) [    ] 
o.a.s.c.ZkController Remove node as live in 
ZooKeeper:/live_nodes/127.0.0.1:40307_solr
   [junit4]   2> 2499090 INFO  (jetty-closer-1722-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 2499091 INFO  
(zkCallback-1745-thread-1-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (1)
   [junit4]   2> 2499091 INFO  
(zkCallback-1749-thread-1-processing-n:127.0.0.1:40307_solr) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (1)
   [junit4]   2> 2499091 INFO  
(zkCallback-1737-thread-2-processing-n:127.0.0.1:40307_solr) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (1)
   [junit4]   2> 2499100 INFO  
(zkCallback-1740-thread-2-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (2) -> (1)
   [junit4]   2> 2499103 INFO  (jetty-closer-1722-thread-2) [    ] 
o.a.s.c.ZkController Remove node as live in 
ZooKeeper:/live_nodes/127.0.0.1:46429_solr
   [junit4]   2> 2499134 INFO  (jetty-closer-1722-thread-2) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 2499135 INFO  
(zkCallback-1745-thread-1-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (0)
   [junit4]   2> 2499135 INFO  
(zkCallback-1740-thread-2-processing-n:127.0.0.1:46429_solr) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (0)
   [junit4]   2> 2499135 INFO  
(zkCallback-1749-thread-1-processing-n:127.0.0.1:40307_solr) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (0)
   [junit4]   2> 2499135 INFO  
(zkCallback-1737-thread-2-processing-n:127.0.0.1:40307_solr) 
[n:127.0.0.1:40307_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from 
ZooKeeper... (1) -> (0)
   [junit4]   2> 2499170 INFO  (jetty-closer-1722-thread-2) [    ] 
o.a.s.c.Overseer Overseer 
(id=72339665651957766-127.0.0.1:46429_solr-n_0000000000) closing
   [junit4]   2> 2499171 INFO  
(OverseerStateUpdate-72339665651957766-127.0.0.1:46429_solr-n_0000000000) 
[n:127.0.0.1:46429_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:46429_solr
   [junit4]   2> 2499214 WARN  
(OverseerAutoScalingTriggerThread-72339665651957766-127.0.0.1:46429_solr-n_0000000000)
 [n:127.0.0.1:46429_solr    ] o.a.s.c.a.OverseerTriggerThread 
OverseerTriggerThread woken up but we are closed, exiting.
   [junit4]   2> 2499242 INFO  (jetty-closer-1722-thread-1) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@49c82b09{/solr,null,UNAVAILABLE}
   [junit4]   2> 2499243 INFO  (jetty-closer-1722-thread-1) [    ] 
o.e.j.s.session Stopped scavenging
   [junit4]   2> 2499275 INFO  (jetty-closer-1722-thread-2) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@71c97123{/solr,null,UNAVAILABLE}
   [junit4]   2> 2499275 INFO  (jetty-closer-1722-thread-2) [    ] 
o.e.j.s.session Stopped scavenging
   [junit4]   2> 2499296 ERROR 
(SUITE-AliasIntegrationTest-seed#[9B1801223018C787]-worker) [    ] 
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper 
server won't take any action on ERROR or SHUTDOWN server state changes
   [junit4]   2> 2499297 INFO  
(SUITE-AliasIntegrationTest-seed#[9B1801223018C787]-worker) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:39869 39869
   [junit4]   2> 2499726 INFO  (Thread-2162) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:39869 39869
   [junit4]   2> 2499752 WARN  (Thread-2162) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        17      /solr/aliases.json
   [junit4]   2>        12      /solr/configs/conf
   [junit4]   2>        5       /solr/collections/collection2meta/terms/shard1
   [junit4]   2>        5       /solr/collections/collection1meta/terms/shard2
   [junit4]   2>        5       /solr/collections/collection1meta/terms/shard1
   [junit4]   2>        4       /solr/collections/collection_one/terms/shard1
   [junit4]   2>        4       /solr/collections/collection_old/terms/shard1
   [junit4]   2>        4       /solr/collections/collection1/terms/shard2
   [junit4]   2>        3       /solr/collections/collection_new/terms/shard1
   [junit4]   2>        3       /solr/collections/collection2/terms/shard1
   [junit4]   2>        3       /solr/collections/collection_two/terms/shard1
   [junit4]   2>        3       /solr/collections/collection_one/terms/shard2
   [junit4]   2>        3       /solr/collections/collection_old/terms/shard2
   [junit4]   2>        3       
/solr/collections/testErrorChecks-collection/terms/shard1
   [junit4]   2>        3       
/solr/collections/testErrorChecks-collection/terms/shard2
   [junit4]   2>        3       /solr/collections/collection1/terms/shard1
   [junit4]   2>        2       /solr/security.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        137     /solr/aliases.json
   [junit4]   2>        22      /solr/collections/collection1meta/state.json
   [junit4]   2>        17      /solr/clusterprops.json
   [junit4]   2>        17      /solr/clusterstate.json
   [junit4]   2>        13      /solr/collections/collection1/state.json
   [junit4]   2>        12      /solr/collections/collection2meta/state.json
   [junit4]   2>        11      /solr/collections/collection_one/state.json
   [junit4]   2>        11      /solr/collections/collection_old/state.json
   [junit4]   2>        11      
/solr/collections/testErrorChecks-collection/state.json
   [junit4]   2>        6       /solr/collections/collection_new/state.json
   [junit4]   2>        6       /solr/collections/collection_two/state.json
   [junit4]   2>        6       /solr/collections/collection2/state.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        104     /solr/collections
   [junit4]   2>        17      /solr/live_nodes
   [junit4]   2> 
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-7.x/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.AliasIntegrationTest_9B1801223018C787-001
   [junit4]   2> Mar 25, 2018 8:32:21 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(Lucene70): 
{a_t=PostingsFormat(name=Asserting), id=FSTOrd50}, docValues:{}, 
maxPointsInLeafNode=1591, maxMBSortInHeap=7.589308866424567, 
sim=RandomSimilarity(queryNorm=true): {}, locale=de-DE, 
timezone=Australia/Lord_Howe
   [junit4]   2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation 
1.8.0_152 (64-bit)/cpus=4,threads=1,free=159732864,total=508559360
   [junit4]   2> NOTE: All tests run in this JVM: [BasicDistributedZkTest, 
AtomicUpdateProcessorFactoryTest, AutoAddReplicasPlanActionTest, 
OpenCloseCoreStressTest, FullSolrCloudDistribCmdsTest, TestInitQParser, 
SolrMetricReporterTest, DistributedExpandComponentTest, 
TestRetrieveFieldsOptimizer, TestRandomFaceting, TestReplicationHandlerBackup, 
BlockCacheTest, XmlUpdateRequestHandlerTest, TestDocSet, 
TestSubQueryTransformer, ShardRoutingTest, ZkNodePropsTest, DateRangeFieldTest, 
SolrGraphiteReporterTest, CollectionStateFormat2Test, TestExecutePlanAction, 
SuggesterWFSTTest, DistributedQueryElevationComponentTest, 
SolrCoreCheckLockOnStartupTest, ShardSplitTest, InfoHandlerTest, 
RAMDirectoryFactoryTest, TestNamedUpdateProcessors, TestReplicaProperties, 
ZkSolrClientTest, TestConfigSetsAPIZkFailure, DeleteNodeTest, 
TriLevelCompositeIdRoutingTest, TestSolrCloudWithSecureImpersonation, 
SynonymTokenizerTest, TestComplexPhraseQParserPlugin, 
ScheduledMaintenanceTriggerTest, TestImpersonationWithHadoopAuth, 
V2StandaloneTest, ConfigureRecoveryStrategyTest, ExitableDirectoryReaderTest, 
TestRebalanceLeaders, TestImplicitCoreProperties, TestIntervalFaceting, 
DeleteStatusTest, AliasIntegrationTest]
   [junit4] Completed [185/785 (1!)] on J0 in 72.04s, 8 tests, 1 failure, 2 
skipped <<< FAILURES!

[...truncated 51748 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to