Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/2784/
Java: 64bit/jdk1.8.0_121 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED:
org.apache.solr.cloud.DistributedVersionInfoTest.testReplicaVersionHandling
Error Message:
Captured an uncaught exception in thread: Thread[id=8908, name=Thread-2275,
state=RUNNABLE, group=TGRP-DistributedVersionInfoTest]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=8908, name=Thread-2275, state=RUNNABLE,
group=TGRP-DistributedVersionInfoTest]
at
__randomizedtesting.SeedInfo.seed([91AD62725D2749CB:4D54B588FF5C838A]:0)
Caused by: java.lang.IllegalArgumentException: bound must be positive
at __randomizedtesting.SeedInfo.seed([91AD62725D2749CB]:0)
at java.util.Random.nextInt(Random.java:388)
at
org.apache.solr.cloud.DistributedVersionInfoTest$3.run(DistributedVersionInfoTest.java:204)
Build Log:
[...truncated 12388 lines...]
[junit4] Suite: org.apache.solr.cloud.DistributedVersionInfoTest
[junit4] 2> Creating dataDir:
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/init-core-data-001
[junit4] 2> 1363706 INFO
(SUITE-DistributedVersionInfoTest-seed#[91AD62725D2749CB]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-5776)
[junit4] 2> 1363706 INFO
(SUITE-DistributedVersionInfoTest-seed#[91AD62725D2749CB]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/tempDir-001
[junit4] 2> 1363706 INFO
(SUITE-DistributedVersionInfoTest-seed#[91AD62725D2749CB]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1363706 INFO (Thread-2245) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1363706 INFO (Thread-2245) [ ] o.a.s.c.ZkTestServer
Starting server
[junit4] 2> 1363806 INFO
(SUITE-DistributedVersionInfoTest-seed#[91AD62725D2749CB]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:46467
[junit4] 2> 1363812 INFO (jetty-launcher-1396-thread-3) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1363812 INFO (jetty-launcher-1396-thread-2) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1363812 INFO (jetty-launcher-1396-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 1363813 INFO (jetty-launcher-1396-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@fbddd8d{/solr,null,AVAILABLE}
[junit4] 2> 1363813 INFO (jetty-launcher-1396-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@1812a838{/solr,null,AVAILABLE}
[junit4] 2> 1363813 INFO (jetty-launcher-1396-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@3f3cb038{/solr,null,AVAILABLE}
[junit4] 2> 1363816 INFO (jetty-launcher-1396-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@6922a077{HTTP/1.1,[http/1.1]}{127.0.0.1:33992}
[junit4] 2> 1363816 INFO (jetty-launcher-1396-thread-1) [ ]
o.e.j.s.Server Started @1365830ms
[junit4] 2> 1363816 INFO (jetty-launcher-1396-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=33992}
[junit4] 2> 1363817 ERROR (jetty-launcher-1396-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1363817 INFO (jetty-launcher-1396-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.5.0
[junit4] 2> 1363817 INFO (jetty-launcher-1396-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1363817 INFO (jetty-launcher-1396-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@4e65c7e3{HTTP/1.1,[http/1.1]}{127.0.0.1:38974}
[junit4] 2> 1363817 INFO (jetty-launcher-1396-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1363817 INFO (jetty-launcher-1396-thread-2) [ ]
o.e.j.s.Server Started @1365830ms
[junit4] 2> 1363817 INFO (jetty-launcher-1396-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-02-02T15:36:16.907Z
[junit4] 2> 1363817 INFO (jetty-launcher-1396-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=38974}
[junit4] 2> 1363817 ERROR (jetty-launcher-1396-thread-2) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1363817 INFO (jetty-launcher-1396-thread-3) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@17ba7746{HTTP/1.1,[http/1.1]}{127.0.0.1:33323}
[junit4] 2> 1363817 INFO (jetty-launcher-1396-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.5.0
[junit4] 2> 1363817 INFO (jetty-launcher-1396-thread-3) [ ]
o.e.j.s.Server Started @1365831ms
[junit4] 2> 1363817 INFO (jetty-launcher-1396-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1363817 INFO (jetty-launcher-1396-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=33323}
[junit4] 2> 1363817 INFO (jetty-launcher-1396-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1363818 INFO (jetty-launcher-1396-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-02-02T15:36:16.908Z
[junit4] 2> 1363818 ERROR (jetty-launcher-1396-thread-3) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 1363818 INFO (jetty-launcher-1396-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version
6.5.0
[junit4] 2> 1363818 INFO (jetty-launcher-1396-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1363818 INFO (jetty-launcher-1396-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1363818 INFO (jetty-launcher-1396-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-02-02T15:36:16.908Z
[junit4] 2> 1363819 INFO (jetty-launcher-1396-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1363819 INFO (jetty-launcher-1396-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1363820 INFO (jetty-launcher-1396-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1363826 INFO (jetty-launcher-1396-thread-1) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1363827 INFO (jetty-launcher-1396-thread-3) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1363827 INFO (jetty-launcher-1396-thread-2) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1363828 INFO (jetty-launcher-1396-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46467/solr
[junit4] 2> 1363828 INFO (jetty-launcher-1396-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46467/solr
[junit4] 2> 1363829 INFO (jetty-launcher-1396-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46467/solr
[junit4] 2> 1363830 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x159ff78149d0005, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1363850 INFO (jetty-launcher-1396-thread-1)
[n:127.0.0.1:33992_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:33992_solr
[junit4] 2> 1363850 INFO (jetty-launcher-1396-thread-3)
[n:127.0.0.1:33323_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33323_solr
[junit4] 2> 1363850 INFO (jetty-launcher-1396-thread-1)
[n:127.0.0.1:33992_solr ] o.a.s.c.Overseer Overseer
(id=97389758172168199-127.0.0.1:33992_solr-n_0000000000) starting
[junit4] 2> 1363851 INFO
(zkCallback-1411-thread-1-processing-n:127.0.0.1:38974_solr)
[n:127.0.0.1:38974_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1363859 INFO
(zkCallback-1412-thread-1-processing-n:127.0.0.1:33323_solr)
[n:127.0.0.1:33323_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1363860 INFO
(zkCallback-1410-thread-1-processing-n:127.0.0.1:33992_solr)
[n:127.0.0.1:33992_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 1363862 INFO (jetty-launcher-1396-thread-2)
[n:127.0.0.1:38974_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:38974_solr
[junit4] 2> 1363863 INFO
(zkCallback-1412-thread-1-processing-n:127.0.0.1:33323_solr)
[n:127.0.0.1:33323_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1363863 INFO
(zkCallback-1411-thread-1-processing-n:127.0.0.1:38974_solr)
[n:127.0.0.1:38974_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1363863 INFO
(zkCallback-1410-thread-1-processing-n:127.0.0.1:33992_solr)
[n:127.0.0.1:33992_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 1363866 INFO (jetty-launcher-1396-thread-1)
[n:127.0.0.1:33992_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:33992_solr
[junit4] 2> 1363868 INFO
(zkCallback-1411-thread-1-processing-n:127.0.0.1:38974_solr)
[n:127.0.0.1:38974_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 1363868 INFO
(zkCallback-1412-thread-1-processing-n:127.0.0.1:33323_solr)
[n:127.0.0.1:33323_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 1363868 INFO
(zkCallback-1410-thread-1-processing-n:127.0.0.1:33992_solr)
[n:127.0.0.1:33992_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 1363930 INFO (jetty-launcher-1396-thread-3)
[n:127.0.0.1:33323_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/tempDir-001/node3/.
[junit4] 2> 1363950 INFO (jetty-launcher-1396-thread-2)
[n:127.0.0.1:38974_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/tempDir-001/node2/.
[junit4] 2> 1363982 INFO (jetty-launcher-1396-thread-1)
[n:127.0.0.1:33992_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/tempDir-001/node1/.
[junit4] 2> 1365380 INFO
(SUITE-DistributedVersionInfoTest-seed#[91AD62725D2749CB]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1365380 INFO
(SUITE-DistributedVersionInfoTest-seed#[91AD62725D2749CB]-worker) [ ]
o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:46467/solr ready
[junit4] 2> 1365400 INFO
(TEST-DistributedVersionInfoTest.testReplicaVersionHandling-seed#[91AD62725D2749CB])
[ ] o.a.s.SolrTestCaseJ4 ###Starting testReplicaVersionHandling
[junit4] 2> 1365402 INFO (qtp243354485-8800) [n:127.0.0.1:33992_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
async=c99f6306-b0ab-4eed-844f-90fe92d56d97&replicationFactor=3&collection.configName=conf&name=c8n_vers_1x3&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1365402 INFO (qtp243354485-8800) [n:127.0.0.1:33992_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={async=c99f6306-b0ab-4eed-844f-90fe92d56d97&replicationFactor=3&collection.configName=conf&name=c8n_vers_1x3&action=CREATE&numShards=1&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1365403 INFO
(OverseerThreadFactory-5003-thread-1-processing-n:127.0.0.1:33992_solr)
[n:127.0.0.1:33992_solr ] o.a.s.c.CreateCollectionCmd Create collection
c8n_vers_1x3
[junit4] 2> 1365405 INFO (qtp243354485-8803) [n:127.0.0.1:33992_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=c99f6306-b0ab-4eed-844f-90fe92d56d97&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1365405 INFO (qtp243354485-8803) [n:127.0.0.1:33992_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=c99f6306-b0ab-4eed-844f-90fe92d56d97&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1365508 INFO (qtp243354485-8787) [n:127.0.0.1:33992_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={async=c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721&qt=/admin/cores&collection.configName=conf&newCollection=true&name=c8n_vers_1x3_shard1_replica2&action=CREATE&numShards=1&collection=c8n_vers_1x3&shard=shard1&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1365508 INFO (qtp1869646694-8801) [n:127.0.0.1:33323_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={async=c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909&qt=/admin/cores&collection.configName=conf&newCollection=true&name=c8n_vers_1x3_shard1_replica3&action=CREATE&numShards=1&collection=c8n_vers_1x3&shard=shard1&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1365508 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr ] o.a.s.h.a.CoreAdminOperation core create command
async=c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909&qt=/admin/cores&collection.configName=conf&newCollection=true&name=c8n_vers_1x3_shard1_replica3&action=CREATE&numShards=1&collection=c8n_vers_1x3&shard=shard1&wt=javabin&version=2
[junit4] 2> 1365509 INFO (qtp50002674-8794) [n:127.0.0.1:38974_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={async=c99f6306-b0ab-4eed-844f-90fe92d56d974756263853880566&qt=/admin/cores&collection.configName=conf&newCollection=true&name=c8n_vers_1x3_shard1_replica1&action=CREATE&numShards=1&collection=c8n_vers_1x3&shard=shard1&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1365509 INFO
(parallelCoreAdminExecutor-5006-thread-1-processing-n:127.0.0.1:38974_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853880566 CREATE)
[n:127.0.0.1:38974_solr ] o.a.s.h.a.CoreAdminOperation core create command
async=c99f6306-b0ab-4eed-844f-90fe92d56d974756263853880566&qt=/admin/cores&collection.configName=conf&newCollection=true&name=c8n_vers_1x3_shard1_replica1&action=CREATE&numShards=1&collection=c8n_vers_1x3&shard=shard1&wt=javabin&version=2
[junit4] 2> 1365508 INFO
(parallelCoreAdminExecutor-5008-thread-1-processing-n:127.0.0.1:33992_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721 CREATE)
[n:127.0.0.1:33992_solr ] o.a.s.h.a.CoreAdminOperation core create command
async=c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721&qt=/admin/cores&collection.configName=conf&newCollection=true&name=c8n_vers_1x3_shard1_replica2&action=CREATE&numShards=1&collection=c8n_vers_1x3&shard=shard1&wt=javabin&version=2
[junit4] 2> 1365510 INFO (qtp243354485-8800) [n:127.0.0.1:33992_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721
[junit4] 2> 1365510 INFO (qtp243354485-8800) [n:127.0.0.1:33992_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1365612 INFO
(zkCallback-1410-thread-1-processing-n:127.0.0.1:33992_solr)
[n:127.0.0.1:33992_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/c8n_vers_1x3/state.json] for collection [c8n_vers_1x3] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1365612 INFO
(zkCallback-1412-thread-1-processing-n:127.0.0.1:33323_solr)
[n:127.0.0.1:33323_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/c8n_vers_1x3/state.json] for collection [c8n_vers_1x3] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1365612 INFO
(zkCallback-1411-thread-1-processing-n:127.0.0.1:38974_solr)
[n:127.0.0.1:38974_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/c8n_vers_1x3/state.json] for collection [c8n_vers_1x3] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1366407 INFO (qtp243354485-8806) [n:127.0.0.1:33992_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=c99f6306-b0ab-4eed-844f-90fe92d56d97&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1366407 INFO (qtp243354485-8806) [n:127.0.0.1:33992_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=c99f6306-b0ab-4eed-844f-90fe92d56d97&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1366512 INFO (qtp243354485-8800) [n:127.0.0.1:33992_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721
[junit4] 2> 1366512 INFO (qtp243354485-8800) [n:127.0.0.1:33992_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1366516 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.c.SolrConfig Using Lucene MatchVersion:
6.5.0
[junit4] 2> 1366516 INFO
(parallelCoreAdminExecutor-5006-thread-1-processing-n:127.0.0.1:38974_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853880566 CREATE)
[n:127.0.0.1:38974_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion:
6.5.0
[junit4] 2> 1366516 INFO
(parallelCoreAdminExecutor-5008-thread-1-processing-n:127.0.0.1:33992_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721 CREATE)
[n:127.0.0.1:33992_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion:
6.5.0
[junit4] 2> 1366523 INFO
(parallelCoreAdminExecutor-5008-thread-1-processing-n:127.0.0.1:33992_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721 CREATE)
[n:127.0.0.1:33992_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica2] o.a.s.s.IndexSchema
[c8n_vers_1x3_shard1_replica2] Schema name=minimal
[junit4] 2> 1366523 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.s.IndexSchema
[c8n_vers_1x3_shard1_replica3] Schema name=minimal
[junit4] 2> 1366524 INFO
(parallelCoreAdminExecutor-5006-thread-1-processing-n:127.0.0.1:38974_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853880566 CREATE)
[n:127.0.0.1:38974_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica1] o.a.s.s.IndexSchema
[c8n_vers_1x3_shard1_replica1] Schema name=minimal
[junit4] 2> 1366525 INFO
(parallelCoreAdminExecutor-5008-thread-1-processing-n:127.0.0.1:33992_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721 CREATE)
[n:127.0.0.1:33992_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica2] o.a.s.s.IndexSchema Loaded schema minimal/1.1
with uniqueid field id
[junit4] 2> 1366525 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.s.IndexSchema Loaded schema minimal/1.1
with uniqueid field id
[junit4] 2> 1366536 INFO
(parallelCoreAdminExecutor-5006-thread-1-processing-n:127.0.0.1:38974_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853880566 CREATE)
[n:127.0.0.1:38974_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica1] o.a.s.s.IndexSchema Loaded schema minimal/1.1
with uniqueid field id
[junit4] 2> 1366536 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.c.CoreContainer Creating SolrCore
'c8n_vers_1x3_shard1_replica3' using configuration from collection c8n_vers_1x3
[junit4] 2> 1366536 INFO
(parallelCoreAdminExecutor-5008-thread-1-processing-n:127.0.0.1:33992_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721 CREATE)
[n:127.0.0.1:33992_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica2] o.a.s.c.CoreContainer Creating SolrCore
'c8n_vers_1x3_shard1_replica2' using configuration from collection c8n_vers_1x3
[junit4] 2> 1366536 INFO
(parallelCoreAdminExecutor-5008-thread-1-processing-n:127.0.0.1:33992_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721 CREATE)
[n:127.0.0.1:33992_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica2] o.a.s.c.SolrCore
[[c8n_vers_1x3_shard1_replica2] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/tempDir-001/node1/c8n_vers_1x3_shard1_replica2],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/tempDir-001/node1/./c8n_vers_1x3_shard1_replica2/data/]
[junit4] 2> 1366536 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.c.SolrCore
[[c8n_vers_1x3_shard1_replica3] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/tempDir-001/node3/c8n_vers_1x3_shard1_replica3],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/tempDir-001/node3/./c8n_vers_1x3_shard1_replica3/data/]
[junit4] 2> 1366536 INFO
(parallelCoreAdminExecutor-5006-thread-1-processing-n:127.0.0.1:38974_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853880566 CREATE)
[n:127.0.0.1:38974_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica1] o.a.s.c.CoreContainer Creating SolrCore
'c8n_vers_1x3_shard1_replica1' using configuration from collection c8n_vers_1x3
[junit4] 2> 1366537 INFO
(parallelCoreAdminExecutor-5006-thread-1-processing-n:127.0.0.1:38974_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853880566 CREATE)
[n:127.0.0.1:38974_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica1] o.a.s.c.SolrCore
[[c8n_vers_1x3_shard1_replica1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/tempDir-001/node2/c8n_vers_1x3_shard1_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/tempDir-001/node2/./c8n_vers_1x3_shard1_replica1/data/]
[junit4] 2> 1366544 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1366544 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.u.UpdateLog Initializing UpdateLog:
dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 1366545 INFO
(parallelCoreAdminExecutor-5008-thread-1-processing-n:127.0.0.1:33992_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721 CREATE)
[n:127.0.0.1:33992_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica2] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1366545 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1366545 INFO
(parallelCoreAdminExecutor-5008-thread-1-processing-n:127.0.0.1:33992_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721 CREATE)
[n:127.0.0.1:33992_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica2] o.a.s.u.UpdateLog Initializing UpdateLog:
dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 1366546 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1366547 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.s.SolrIndexSearcher Opening
[Searcher@267c3181[c8n_vers_1x3_shard1_replica3] main]
[junit4] 2> 1366547 INFO
(parallelCoreAdminExecutor-5008-thread-1-processing-n:127.0.0.1:33992_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721 CREATE)
[n:127.0.0.1:33992_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1366547 INFO
(parallelCoreAdminExecutor-5008-thread-1-processing-n:127.0.0.1:33992_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721 CREATE)
[n:127.0.0.1:33992_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1366547 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.r.ManagedResourceStorage Configured
ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1366547 INFO
(parallelCoreAdminExecutor-5008-thread-1-processing-n:127.0.0.1:33992_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721 CREATE)
[n:127.0.0.1:33992_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica2] o.a.s.s.SolrIndexSearcher Opening
[Searcher@36e2a2b2[c8n_vers_1x3_shard1_replica2] main]
[junit4] 2> 1366547 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.r.ManagedResourceStorage Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1366547 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.h.ReplicationHandler Commits will be
reserved for 10000
[junit4] 2> 1366548 INFO
(searcherExecutor-5010-thread-1-processing-x:c8n_vers_1x3_shard1_replica3
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE
n:127.0.0.1:33323_solr s:shard1 c:c8n_vers_1x3) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 x:c8n_vers_1x3_shard1_replica3] o.a.s.c.SolrCore
[c8n_vers_1x3_shard1_replica3] Registered new searcher
Searcher@267c3181[c8n_vers_1x3_shard1_replica3]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1366548 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.u.UpdateLog Could not find max version in
index or recent updates, using new clock 1558236133733695488
[junit4] 2> 1366548 INFO
(parallelCoreAdminExecutor-5008-thread-1-processing-n:127.0.0.1:33992_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721 CREATE)
[n:127.0.0.1:33992_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica2] o.a.s.r.ManagedResourceStorage Configured
ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1366548 INFO
(parallelCoreAdminExecutor-5008-thread-1-processing-n:127.0.0.1:33992_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721 CREATE)
[n:127.0.0.1:33992_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica2] o.a.s.r.ManagedResourceStorage Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1366548 INFO
(parallelCoreAdminExecutor-5006-thread-1-processing-n:127.0.0.1:38974_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853880566 CREATE)
[n:127.0.0.1:38974_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica1] o.a.s.u.UpdateHandler Using UpdateLog
implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1366548 INFO
(parallelCoreAdminExecutor-5008-thread-1-processing-n:127.0.0.1:33992_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721 CREATE)
[n:127.0.0.1:33992_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica2] o.a.s.h.ReplicationHandler Commits will be
reserved for 10000
[junit4] 2> 1366548 INFO
(parallelCoreAdminExecutor-5006-thread-1-processing-n:127.0.0.1:38974_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853880566 CREATE)
[n:127.0.0.1:38974_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica1] o.a.s.u.UpdateLog Initializing UpdateLog:
dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 1366548 INFO
(searcherExecutor-5011-thread-1-processing-x:c8n_vers_1x3_shard1_replica2
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721 CREATE
n:127.0.0.1:33992_solr s:shard1 c:c8n_vers_1x3) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 x:c8n_vers_1x3_shard1_replica2] o.a.s.c.SolrCore
[c8n_vers_1x3_shard1_replica2] Registered new searcher
Searcher@36e2a2b2[c8n_vers_1x3_shard1_replica2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1366548 INFO
(parallelCoreAdminExecutor-5008-thread-1-processing-n:127.0.0.1:33992_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721 CREATE)
[n:127.0.0.1:33992_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica2] o.a.s.u.UpdateLog Could not find max version in
index or recent updates, using new clock 1558236133733695488
[junit4] 2> 1366549 INFO
(parallelCoreAdminExecutor-5006-thread-1-processing-n:127.0.0.1:38974_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853880566 CREATE)
[n:127.0.0.1:38974_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1366549 INFO
(parallelCoreAdminExecutor-5006-thread-1-processing-n:127.0.0.1:38974_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853880566 CREATE)
[n:127.0.0.1:38974_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1366550 INFO
(parallelCoreAdminExecutor-5006-thread-1-processing-n:127.0.0.1:38974_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853880566 CREATE)
[n:127.0.0.1:38974_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@1e1eb119[c8n_vers_1x3_shard1_replica1] main]
[junit4] 2> 1366551 INFO
(parallelCoreAdminExecutor-5006-thread-1-processing-n:127.0.0.1:38974_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853880566 CREATE)
[n:127.0.0.1:38974_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica1] o.a.s.r.ManagedResourceStorage Configured
ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 1366551 INFO
(parallelCoreAdminExecutor-5006-thread-1-processing-n:127.0.0.1:38974_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853880566 CREATE)
[n:127.0.0.1:38974_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica1] o.a.s.r.ManagedResourceStorage Loaded null at
path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1366551 INFO
(parallelCoreAdminExecutor-5006-thread-1-processing-n:127.0.0.1:38974_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853880566 CREATE)
[n:127.0.0.1:38974_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica1] o.a.s.h.ReplicationHandler Commits will be
reserved for 10000
[junit4] 2> 1366551 INFO
(searcherExecutor-5012-thread-1-processing-x:c8n_vers_1x3_shard1_replica1
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853880566 CREATE
n:127.0.0.1:38974_solr s:shard1 c:c8n_vers_1x3) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 x:c8n_vers_1x3_shard1_replica1] o.a.s.c.SolrCore
[c8n_vers_1x3_shard1_replica1] Registered new searcher
Searcher@1e1eb119[c8n_vers_1x3_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1366551 INFO
(parallelCoreAdminExecutor-5006-thread-1-processing-n:127.0.0.1:38974_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853880566 CREATE)
[n:127.0.0.1:38974_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica1] o.a.s.u.UpdateLog Could not find max version in
index or recent updates, using new clock 1558236133736841216
[junit4] 2> 1366552 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.c.ShardLeaderElectionContext Waiting
until we see more replicas up for shard shard1: total=3 found=2 timeoutin=9999ms
[junit4] 2> 1366654 INFO
(zkCallback-1412-thread-1-processing-n:127.0.0.1:33323_solr)
[n:127.0.0.1:33323_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/c8n_vers_1x3/state.json] for collection [c8n_vers_1x3] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1366654 INFO
(zkCallback-1410-thread-1-processing-n:127.0.0.1:33992_solr)
[n:127.0.0.1:33992_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/c8n_vers_1x3/state.json] for collection [c8n_vers_1x3] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1366654 INFO
(zkCallback-1411-thread-1-processing-n:127.0.0.1:38974_solr)
[n:127.0.0.1:38974_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/c8n_vers_1x3/state.json] for collection [c8n_vers_1x3] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1367053 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.c.ShardLeaderElectionContext Enough
replicas found to continue.
[junit4] 2> 1367053 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.c.ShardLeaderElectionContext I may be the
new leader - try and sync
[junit4] 2> 1367053 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.c.SyncStrategy Sync replicas to
http://127.0.0.1:33323/solr/c8n_vers_1x3_shard1_replica3/
[junit4] 2> 1367053 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.u.PeerSync PeerSync:
core=c8n_vers_1x3_shard1_replica3 url=http://127.0.0.1:33323/solr START
replicas=[http://127.0.0.1:33992/solr/c8n_vers_1x3_shard1_replica2/,
http://127.0.0.1:38974/solr/c8n_vers_1x3_shard1_replica1/] nUpdates=100
[junit4] 2> 1367054 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.u.PeerSync PeerSync:
core=c8n_vers_1x3_shard1_replica3 url=http://127.0.0.1:33323/solr DONE. We
have no versions. sync failed.
[junit4] 2> 1367055 INFO (qtp243354485-8803) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.c.S.Request [c8n_vers_1x3_shard1_replica2] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1367055 INFO (qtp50002674-8785) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.c.S.Request [c8n_vers_1x3_shard1_replica1] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1367055 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.c.SyncStrategy Leader's attempt to sync
with shard failed, moving to the next candidate
[junit4] 2> 1367056 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.c.ShardLeaderElectionContext We failed
sync, but we have no versions - we can't sync in that case - we were active
before, so become leader anyway
[junit4] 2> 1367057 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.c.ShardLeaderElectionContext I am the new
leader: http://127.0.0.1:33323/solr/c8n_vers_1x3_shard1_replica3/ shard1
[junit4] 2> 1367159 INFO
(zkCallback-1411-thread-1-processing-n:127.0.0.1:38974_solr)
[n:127.0.0.1:38974_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/c8n_vers_1x3/state.json] for collection [c8n_vers_1x3] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1367159 INFO
(zkCallback-1412-thread-1-processing-n:127.0.0.1:33323_solr)
[n:127.0.0.1:33323_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/c8n_vers_1x3/state.json] for collection [c8n_vers_1x3] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1367159 INFO
(zkCallback-1410-thread-3-processing-n:127.0.0.1:33992_solr)
[n:127.0.0.1:33992_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/c8n_vers_1x3/state.json] for collection [c8n_vers_1x3] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1367208 INFO
(parallelCoreAdminExecutor-5004-thread-1-processing-n:127.0.0.1:33323_solr
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909 CREATE)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1
x:c8n_vers_1x3_shard1_replica3] o.a.s.c.ZkController I am the leader, no
recovery necessary
[junit4] 2> 1367310 INFO
(zkCallback-1410-thread-3-processing-n:127.0.0.1:33992_solr)
[n:127.0.0.1:33992_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/c8n_vers_1x3/state.json] for collection [c8n_vers_1x3] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1367310 INFO
(zkCallback-1412-thread-1-processing-n:127.0.0.1:33323_solr)
[n:127.0.0.1:33323_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/c8n_vers_1x3/state.json] for collection [c8n_vers_1x3] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1367310 INFO
(zkCallback-1411-thread-1-processing-n:127.0.0.1:38974_solr)
[n:127.0.0.1:38974_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/c8n_vers_1x3/state.json] for collection [c8n_vers_1x3] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1367409 INFO (qtp243354485-8790) [n:127.0.0.1:33992_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=c99f6306-b0ab-4eed-844f-90fe92d56d97&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1367411 INFO (qtp243354485-8790) [n:127.0.0.1:33992_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=c99f6306-b0ab-4eed-844f-90fe92d56d97&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 1367514 INFO (qtp243354485-8787) [n:127.0.0.1:33992_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721
[junit4] 2> 1367514 INFO (qtp243354485-8787) [n:127.0.0.1:33992_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1367659 INFO
(zkCallback-1410-thread-3-processing-n:127.0.0.1:33992_solr)
[n:127.0.0.1:33992_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/c8n_vers_1x3/state.json] for collection [c8n_vers_1x3] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1367659 INFO
(zkCallback-1412-thread-1-processing-n:127.0.0.1:33323_solr)
[n:127.0.0.1:33323_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/c8n_vers_1x3/state.json] for collection [c8n_vers_1x3] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1367659 INFO
(zkCallback-1411-thread-1-processing-n:127.0.0.1:38974_solr)
[n:127.0.0.1:38974_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/c8n_vers_1x3/state.json] for collection [c8n_vers_1x3] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1368413 INFO (qtp243354485-8800) [n:127.0.0.1:33992_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=c99f6306-b0ab-4eed-844f-90fe92d56d97&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1368413 INFO (qtp243354485-8800) [n:127.0.0.1:33992_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=c99f6306-b0ab-4eed-844f-90fe92d56d97&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1368516 INFO (qtp243354485-8795) [n:127.0.0.1:33992_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721
[junit4] 2> 1368516 INFO (qtp243354485-8795) [n:127.0.0.1:33992_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=c99f6306-b0ab-4eed-844f-90fe92d56d974756263853131721&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1368518 INFO (qtp50002674-8797) [n:127.0.0.1:38974_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853880566
[junit4] 2> 1368518 INFO (qtp50002674-8797) [n:127.0.0.1:38974_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=c99f6306-b0ab-4eed-844f-90fe92d56d974756263853880566&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1368528 INFO (qtp1869646694-8804) [n:127.0.0.1:33323_solr
] o.a.s.h.a.CoreAdminOperation Checking request status for :
c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909
[junit4] 2> 1368528 INFO (qtp1869646694-8804) [n:127.0.0.1:33323_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=c99f6306-b0ab-4eed-844f-90fe92d56d974756263853258909&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1368531 INFO
(OverseerThreadFactory-5003-thread-1-processing-n:127.0.0.1:33992_solr)
[n:127.0.0.1:33992_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> 1369405 INFO
(OverseerCollectionConfigSetProcessor-97389758172168199-127.0.0.1:33992_solr-n_0000000000)
[n:127.0.0.1:33992_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> 1369416 INFO (qtp243354485-8803) [n:127.0.0.1:33992_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=c99f6306-b0ab-4eed-844f-90fe92d56d97&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1369416 INFO (qtp243354485-8803) [n:127.0.0.1:33992_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=c99f6306-b0ab-4eed-844f-90fe92d56d97&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1369418 INFO (qtp243354485-8787) [n:127.0.0.1:33992_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletestatus with
params
requestid=c99f6306-b0ab-4eed-844f-90fe92d56d97&action=DELETESTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 1369418 INFO (qtp243354485-8787) [n:127.0.0.1:33992_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=c99f6306-b0ab-4eed-844f-90fe92d56d97&action=DELETESTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1369429 INFO (qtp1869646694-8784) [n:127.0.0.1:33323_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={core=c8n_vers_1x3_shard1_replica3&action=STATUS&indexInfo=true&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1370430 INFO
(TEST-DistributedVersionInfoTest.testReplicaVersionHandling-seed#[91AD62725D2749CB])
[ ] o.a.s.c.DistributedVersionInfoTest Sending RELOAD command for
c8n_vers_1x3
[junit4] 2> 1370432 INFO (qtp1869646694-8801) [n:127.0.0.1:33323_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :reload with params
name=c8n_vers_1x3&action=RELOAD&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1370434 INFO
(OverseerThreadFactory-5003-thread-2-processing-n:127.0.0.1:33992_solr)
[n:127.0.0.1:33992_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=RELOAD
[junit4] 2> 1370441 INFO (qtp243354485-8790) [n:127.0.0.1:33992_solr ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
[junit4] 2> 1370442 INFO (qtp50002674-8794) [n:127.0.0.1:38974_solr ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
[junit4] 2> 1370449 INFO (qtp1869646694-8808) [n:127.0.0.1:33323_solr
] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
[junit4] 2> 1370450 INFO (qtp243354485-8790) [n:127.0.0.1:33992_solr ]
o.a.s.s.IndexSchema [c8n_vers_1x3_shard1_replica2] Schema name=minimal
[junit4] 2> 1370451 INFO (qtp50002674-8794) [n:127.0.0.1:38974_solr ]
o.a.s.s.IndexSchema [c8n_vers_1x3_shard1_replica1] Schema name=minimal
[junit4] 2> 1370453 INFO (qtp243354485-8790) [n:127.0.0.1:33992_solr ]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1370454 INFO (qtp50002674-8794) [n:127.0.0.1:38974_solr ]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1370454 INFO (qtp243354485-8790) [n:127.0.0.1:33992_solr ]
o.a.s.c.CoreContainer Reloading SolrCore 'c8n_vers_1x3_shard1_replica2' using
configuration from collection c8n_vers_1x3
[junit4] 2> 1370454 INFO (qtp50002674-8794) [n:127.0.0.1:38974_solr ]
o.a.s.c.CoreContainer Reloading SolrCore 'c8n_vers_1x3_shard1_replica1' using
configuration from collection c8n_vers_1x3
[junit4] 2> 1370455 INFO (qtp243354485-8790) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.c.SolrCore [[c8n_vers_1x3_shard1_replica2] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/tempDir-001/node1/c8n_vers_1x3_shard1_replica2],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/tempDir-001/node1/./c8n_vers_1x3_shard1_replica2/data/]
[junit4] 2> 1370455 INFO (qtp50002674-8794) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.c.SolrCore [[c8n_vers_1x3_shard1_replica1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/tempDir-001/node2/c8n_vers_1x3_shard1_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/tempDir-001/node2/./c8n_vers_1x3_shard1_replica1/data/]
[junit4] 2> 1370456 INFO (qtp1869646694-8808) [n:127.0.0.1:33323_solr
] o.a.s.s.IndexSchema [c8n_vers_1x3_shard1_replica3] Schema name=minimal
[junit4] 2> 1370460 INFO (qtp1869646694-8808) [n:127.0.0.1:33323_solr
] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1370461 INFO (qtp1869646694-8808) [n:127.0.0.1:33323_solr
] o.a.s.c.CoreContainer Reloading SolrCore 'c8n_vers_1x3_shard1_replica3' using
configuration from collection c8n_vers_1x3
[junit4] 2> 1370461 INFO (qtp1869646694-8808) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.c.SolrCore [[c8n_vers_1x3_shard1_replica3] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/tempDir-001/node3/c8n_vers_1x3_shard1_replica3],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/tempDir-001/node3/./c8n_vers_1x3_shard1_replica3/data/]
[junit4] 2> 1370466 INFO (qtp50002674-8794) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1370466 INFO (qtp50002674-8794) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1370466 INFO (qtp50002674-8794) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@418e8b4e[c8n_vers_1x3_shard1_replica1] main]
[junit4] 2> 1370467 INFO (qtp50002674-8794) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1370467 INFO (qtp50002674-8794) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1370467 INFO (qtp50002674-8794) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1370467 INFO (qtp243354485-8790) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1370467 INFO (qtp243354485-8790) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1370467 INFO
(searcherExecutor-5026-thread-1-processing-n:127.0.0.1:38974_solr
x:c8n_vers_1x3_shard1_replica1 s:shard1 c:c8n_vers_1x3 r:core_node2)
[n:127.0.0.1:38974_solr c:c8n_vers_1x3 s:shard1 r:core_node2
x:c8n_vers_1x3_shard1_replica1] o.a.s.c.SolrCore [c8n_vers_1x3_shard1_replica1]
Registered new searcher Searcher@418e8b4e[c8n_vers_1x3_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1370467 INFO (qtp50002674-8794) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1558236137843064832
[junit4] 2> 1370467 INFO (qtp243354485-8790) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@22c52dfe[c8n_vers_1x3_shard1_replica2] main]
[junit4] 2> 1370468 INFO (qtp243354485-8790) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1370468 INFO (qtp243354485-8790) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1370468 INFO (qtp243354485-8790) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1370468 INFO
(searcherExecutor-5025-thread-1-processing-n:127.0.0.1:33992_solr
x:c8n_vers_1x3_shard1_replica2 s:shard1 c:c8n_vers_1x3 r:core_node1)
[n:127.0.0.1:33992_solr c:c8n_vers_1x3 s:shard1 r:core_node1
x:c8n_vers_1x3_shard1_replica2] o.a.s.c.SolrCore [c8n_vers_1x3_shard1_replica2]
Registered new searcher Searcher@22c52dfe[c8n_vers_1x3_shard1_replica2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1370468 INFO (qtp243354485-8790) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1558236137844113408
[junit4] 2> 1370471 INFO (qtp50002674-8794) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1370472 INFO (qtp50002674-8794) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@19b41856[c8n_vers_1x3_shard1_replica1] main]
[junit4] 2> 1370472 INFO
(searcherExecutor-5026-thread-1-processing-n:127.0.0.1:38974_solr
x:c8n_vers_1x3_shard1_replica1 s:shard1 c:c8n_vers_1x3 r:core_node2)
[n:127.0.0.1:38974_solr c:c8n_vers_1x3 s:shard1 r:core_node2
x:c8n_vers_1x3_shard1_replica1] o.a.s.c.SolrCore [c8n_vers_1x3_shard1_replica1]
Registered new searcher Searcher@19b41856[c8n_vers_1x3_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1370472 INFO (qtp50002674-8794) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.c.SolrCore [c8n_vers_1x3_shard1_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@4ec83501
[junit4] 2> 1370472 INFO (qtp50002674-8794) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.c8n_vers_1x3.shard1.replica1
[junit4] 2> 1370472 INFO (qtp50002674-8794) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={core=c8n_vers_1x3_shard1_replica1&qt=/admin/cores&action=RELOAD&wt=javabin&version=2}
status=0 QTime=35
[junit4] 2> 1370473 INFO (qtp243354485-8790) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1370474 INFO (qtp243354485-8790) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@12b423b9[c8n_vers_1x3_shard1_replica2] main]
[junit4] 2> 1370474 INFO
(searcherExecutor-5025-thread-1-processing-n:127.0.0.1:33992_solr
x:c8n_vers_1x3_shard1_replica2 s:shard1 c:c8n_vers_1x3 r:core_node1)
[n:127.0.0.1:33992_solr c:c8n_vers_1x3 s:shard1 r:core_node1
x:c8n_vers_1x3_shard1_replica2] o.a.s.c.SolrCore [c8n_vers_1x3_shard1_replica2]
Registered new searcher Searcher@12b423b9[c8n_vers_1x3_shard1_replica2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1370474 INFO (qtp243354485-8790) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.c.SolrCore [c8n_vers_1x3_shard1_replica2] CLOSING SolrCore
org.apache.solr.core.SolrCore@657cea55
[junit4] 2> 1370474 INFO (qtp243354485-8790) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.c8n_vers_1x3.shard1.replica2
[junit4] 2> 1370474 INFO (qtp243354485-8790) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={core=c8n_vers_1x3_shard1_replica2&qt=/admin/cores&action=RELOAD&wt=javabin&version=2}
status=0 QTime=38
[junit4] 2> 1370474 INFO (qtp1869646694-8808) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1370474 INFO (qtp1869646694-8808) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1370474 INFO (qtp1869646694-8808) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@42f5a6f1[c8n_vers_1x3_shard1_replica3] main]
[junit4] 2> 1370475 INFO (qtp1869646694-8808) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1370475 INFO (qtp1869646694-8808) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1370475 INFO (qtp1869646694-8808) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1370475 INFO
(searcherExecutor-5028-thread-1-processing-n:127.0.0.1:33323_solr
x:c8n_vers_1x3_shard1_replica3 s:shard1 c:c8n_vers_1x3 r:core_node3)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1 r:core_node3
x:c8n_vers_1x3_shard1_replica3] o.a.s.c.SolrCore [c8n_vers_1x3_shard1_replica3]
Registered new searcher Searcher@42f5a6f1[c8n_vers_1x3_shard1_replica3]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1370475 INFO (qtp1869646694-8808) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1558236137851453440
[junit4] 2> 1370479 INFO (qtp1869646694-8808) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1370479 INFO (qtp1869646694-8808) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@6f4d064[c8n_vers_1x3_shard1_replica3] main]
[junit4] 2> 1370479 INFO (qtp1869646694-8808) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.c.SolrCore [c8n_vers_1x3_shard1_replica3] CLOSING SolrCore
org.apache.solr.core.SolrCore@355c989f
[junit4] 2> 1370479 INFO
(searcherExecutor-5028-thread-1-processing-n:127.0.0.1:33323_solr
x:c8n_vers_1x3_shard1_replica3 s:shard1 c:c8n_vers_1x3 r:core_node3)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1 r:core_node3
x:c8n_vers_1x3_shard1_replica3] o.a.s.c.SolrCore [c8n_vers_1x3_shard1_replica3]
Registered new searcher Searcher@6f4d064[c8n_vers_1x3_shard1_replica3]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1370479 INFO (qtp1869646694-8808) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.c8n_vers_1x3.shard1.replica3
[junit4] 2> 1370479 INFO (qtp1869646694-8808) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={core=c8n_vers_1x3_shard1_replica3&qt=/admin/cores&action=RELOAD&wt=javabin&version=2}
status=0 QTime=42
[junit4] 2> 1370481 INFO (qtp1869646694-8801) [n:127.0.0.1:33323_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=c8n_vers_1x3&action=RELOAD&wt=javabin&version=2} status=0 QTime=48
[junit4] 2> 1372435 INFO
(OverseerCollectionConfigSetProcessor-97389758172168199-127.0.0.1:33992_solr-n_0000000000)
[n:127.0.0.1:33992_solr ] o.a.s.c.OverseerTaskQueue Response ZK path:
/overseer/collection-queue-work/qnr-0000000001 doesn't exist. Requestor may
have disconnected from ZooKeeper
[junit4] 2> 1372483 INFO (qtp1869646694-8804) [n:127.0.0.1:33323_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={core=c8n_vers_1x3_shard1_replica3&action=STATUS&indexInfo=true&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1372496 INFO (qtp243354485-8806) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.u.p.LogUpdateProcessorFactory [c8n_vers_1x3_shard1_replica2]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:33323/solr/c8n_vers_1x3_shard1_replica3/&min_rf=2&wt=javabin&version=2}{add=[1
(1558236139961188352)]} 0 5
[junit4] 2> 1372496 INFO (qtp50002674-8783) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [c8n_vers_1x3_shard1_replica1]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:33323/solr/c8n_vers_1x3_shard1_replica3/&min_rf=2&wt=javabin&version=2}{add=[1
(1558236139961188352)]} 0 1
[junit4] 2> 1372499 INFO (qtp1869646694-8804) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.u.p.LogUpdateProcessorFactory [c8n_vers_1x3_shard1_replica3]
webapp=/solr path=/update
params={_stateVer_=c8n_vers_1x3:5&min_rf=2&wt=javabin&version=2}{add=[1
(1558236139961188352)]} 0 13
[junit4] 2> 1372503 INFO (qtp1869646694-8793) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1372503 INFO (qtp1869646694-8793) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@349fc0d4
[junit4] 2> 1372503 INFO (qtp243354485-8803) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1372504 INFO (qtp243354485-8803) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@23ced628
[junit4] 2> 1372505 INFO (qtp1869646694-8793) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@77f04956[c8n_vers_1x3_shard1_replica3] main]
[junit4] 2> 1372505 INFO (qtp50002674-8797) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1372505 INFO (qtp243354485-8803) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@5a553c3d[c8n_vers_1x3_shard1_replica2] main]
[junit4] 2> 1372505 INFO (qtp50002674-8797) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@8b18122
[junit4] 2> 1372505 INFO (qtp1869646694-8793) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1372506 INFO (qtp243354485-8803) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1372505 INFO
(searcherExecutor-5028-thread-1-processing-n:127.0.0.1:33323_solr
x:c8n_vers_1x3_shard1_replica3 s:shard1 c:c8n_vers_1x3 r:core_node3)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1 r:core_node3
x:c8n_vers_1x3_shard1_replica3] o.a.s.c.SolrCore [c8n_vers_1x3_shard1_replica3]
Registered new searcher Searcher@77f04956[c8n_vers_1x3_shard1_replica3]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C1)))}
[junit4] 2> 1372506 INFO
(searcherExecutor-5025-thread-1-processing-n:127.0.0.1:33992_solr
x:c8n_vers_1x3_shard1_replica2 s:shard1 c:c8n_vers_1x3 r:core_node1)
[n:127.0.0.1:33992_solr c:c8n_vers_1x3 s:shard1 r:core_node1
x:c8n_vers_1x3_shard1_replica2] o.a.s.c.SolrCore [c8n_vers_1x3_shard1_replica2]
Registered new searcher Searcher@5a553c3d[c8n_vers_1x3_shard1_replica2]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C1)))}
[junit4] 2> 1372506 INFO (qtp1869646694-8793) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.u.p.LogUpdateProcessorFactory [c8n_vers_1x3_shard1_replica3]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:33323/solr/c8n_vers_1x3_shard1_replica3/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 3
[junit4] 2> 1372506 INFO (qtp243354485-8803) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.u.p.LogUpdateProcessorFactory [c8n_vers_1x3_shard1_replica2]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:33323/solr/c8n_vers_1x3_shard1_replica3/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 2
[junit4] 2> 1372507 INFO (qtp50002674-8797) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@3282e31f[c8n_vers_1x3_shard1_replica1] main]
[junit4] 2> 1372508 INFO (qtp50002674-8797) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1372508 INFO
(searcherExecutor-5026-thread-1-processing-n:127.0.0.1:38974_solr
x:c8n_vers_1x3_shard1_replica1 s:shard1 c:c8n_vers_1x3 r:core_node2)
[n:127.0.0.1:38974_solr c:c8n_vers_1x3 s:shard1 r:core_node2
x:c8n_vers_1x3_shard1_replica1] o.a.s.c.SolrCore [c8n_vers_1x3_shard1_replica1]
Registered new searcher Searcher@3282e31f[c8n_vers_1x3_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C1)))}
[junit4] 2> 1372508 INFO (qtp50002674-8797) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [c8n_vers_1x3_shard1_replica1]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:33323/solr/c8n_vers_1x3_shard1_replica3/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 2
[junit4] 2> 1372508 INFO (qtp1869646694-8784) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.u.p.LogUpdateProcessorFactory [c8n_vers_1x3_shard1_replica3]
webapp=/solr path=/update
params={_stateVer_=c8n_vers_1x3:5&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 6
[junit4] 2> 1372512 INFO (qtp1869646694-8789) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.c.S.Request [c8n_vers_1x3_shard1_replica3] webapp=/solr path=/get
params={distrib=false&qt=/get&fl=id,_version_&id=1&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1372513 INFO (qtp243354485-8790) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.c.S.Request [c8n_vers_1x3_shard1_replica2] webapp=/solr path=/get
params={distrib=false&qt=/get&fl=id,_version_&id=1&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1372514 INFO (qtp50002674-8785) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.c.S.Request [c8n_vers_1x3_shard1_replica1] webapp=/solr path=/get
params={distrib=false&qt=/get&fl=id,_version_&id=1&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1372516 INFO (qtp1869646694-8808) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.c.S.Request [c8n_vers_1x3_shard1_replica3] webapp=/solr path=/select
params={q=_version_:[0+TO+*]&distrib=false&fl=id,_version_&sort=_version_+desc&rows=1&wt=javabin&version=2}
hits=1 status=0 QTime=0
[junit4] 2> 1372517 INFO (qtp243354485-8796) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.c.S.Request [c8n_vers_1x3_shard1_replica2] webapp=/solr path=/select
params={q=_version_:[0+TO+*]&distrib=false&fl=id,_version_&sort=_version_+desc&rows=1&wt=javabin&version=2}
hits=1 status=0 QTime=0
[junit4] 2> 1372518 INFO
(TEST-DistributedVersionInfoTest.testReplicaVersionHandling-seed#[91AD62725D2749CB])
[ ] o.a.s.c.DistributedVersionInfoTest Sending doc with out-of-date version
(1558236139961188351) document directly to replica
[junit4] 2> 1372519 INFO (qtp243354485-8796) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.u.p.LogUpdateProcessorFactory [c8n_vers_1x3_shard1_replica2]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:33323/solr/c8n_vers_1x3_shard1_replica3/&wt=javabin&version=2}{add=[1
(1558236139961188351)]} 0 0
[junit4] 2> 1372525 INFO (qtp243354485-8787) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1372525 INFO (qtp50002674-8791) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1372525 INFO (qtp50002674-8791) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1372525 INFO (qtp243354485-8787) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1372525 INFO (qtp50002674-8791) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1372525 INFO (qtp50002674-8791) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [c8n_vers_1x3_shard1_replica1]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:33992/solr/c8n_vers_1x3_shard1_replica2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 1372525 INFO (qtp243354485-8787) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1372525 INFO (qtp243354485-8787) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.u.p.LogUpdateProcessorFactory [c8n_vers_1x3_shard1_replica2]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:33992/solr/c8n_vers_1x3_shard1_replica2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 1372526 INFO (qtp1869646694-8808) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1372526 INFO (qtp1869646694-8808) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 1372526 INFO (qtp1869646694-8808) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1372526 INFO (qtp1869646694-8808) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.u.p.LogUpdateProcessorFactory [c8n_vers_1x3_shard1_replica3]
webapp=/solr path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:33992/solr/c8n_vers_1x3_shard1_replica2/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 1372526 INFO (qtp243354485-8790) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.u.p.LogUpdateProcessorFactory [c8n_vers_1x3_shard1_replica2]
webapp=/solr path=/update
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 6
[junit4] 2> 1372528 INFO (qtp243354485-8803) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.c.S.Request [c8n_vers_1x3_shard1_replica2] webapp=/solr path=/select
params={q=id:1&distrib=false&fl=id,_version_&sort=_version_+desc&rows=1&wt=javabin&version=2}
hits=1 status=0 QTime=0
[junit4] 2> 1372529 INFO (qtp1869646694-8793) [n:127.0.0.1:33323_solr
] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={core=c8n_vers_1x3_shard1_replica3&action=STATUS&indexInfo=true&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 1373530 INFO
(TEST-DistributedVersionInfoTest.testReplicaVersionHandling-seed#[91AD62725D2749CB])
[ ] o.a.s.c.DistributedVersionInfoTest Sending RELOAD command for
c8n_vers_1x3
[junit4] 2> 1373532 INFO (qtp1869646694-8784) [n:127.0.0.1:33323_solr
] o.a.s.h.a.CollectionsHandler Invoked Collection Action :reload with params
name=c8n_vers_1x3&action=RELOAD&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1373534 INFO
(OverseerThreadFactory-5003-thread-3-processing-n:127.0.0.1:33992_solr)
[n:127.0.0.1:33992_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=RELOAD
[junit4] 2> 1373562 INFO (qtp50002674-8802) [n:127.0.0.1:38974_solr ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
[junit4] 2> 1373562 INFO (qtp243354485-8806) [n:127.0.0.1:33992_solr ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
[junit4] 2> 1373562 INFO (qtp1869646694-8786) [n:127.0.0.1:33323_solr
] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.0
[junit4] 2> 1373569 INFO (qtp50002674-8802) [n:127.0.0.1:38974_solr ]
o.a.s.s.IndexSchema [c8n_vers_1x3_shard1_replica1] Schema name=minimal
[junit4] 2> 1373571 INFO (qtp1869646694-8786) [n:127.0.0.1:33323_solr
] o.a.s.s.IndexSchema [c8n_vers_1x3_shard1_replica3] Schema name=minimal
[junit4] 2> 1373571 INFO (qtp243354485-8806) [n:127.0.0.1:33992_solr ]
o.a.s.s.IndexSchema [c8n_vers_1x3_shard1_replica2] Schema name=minimal
[junit4] 2> 1373571 INFO (qtp50002674-8802) [n:127.0.0.1:38974_solr ]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1373571 INFO (qtp50002674-8802) [n:127.0.0.1:38974_solr ]
o.a.s.c.CoreContainer Reloading SolrCore 'c8n_vers_1x3_shard1_replica1' using
configuration from collection c8n_vers_1x3
[junit4] 2> 1373572 INFO (qtp50002674-8802) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.c.SolrCore [[c8n_vers_1x3_shard1_replica1] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/tempDir-001/node2/c8n_vers_1x3_shard1_replica1],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/tempDir-001/node2/./c8n_vers_1x3_shard1_replica1/data/]
[junit4] 2> 1373573 INFO (qtp243354485-8806) [n:127.0.0.1:33992_solr ]
o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1373573 INFO (qtp1869646694-8786) [n:127.0.0.1:33323_solr
] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 1373574 INFO (qtp243354485-8806) [n:127.0.0.1:33992_solr ]
o.a.s.c.CoreContainer Reloading SolrCore 'c8n_vers_1x3_shard1_replica2' using
configuration from collection c8n_vers_1x3
[junit4] 2> 1373574 INFO (qtp1869646694-8786) [n:127.0.0.1:33323_solr
] o.a.s.c.CoreContainer Reloading SolrCore 'c8n_vers_1x3_shard1_replica3' using
configuration from collection c8n_vers_1x3
[junit4] 2> 1373574 INFO (qtp243354485-8806) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.c.SolrCore [[c8n_vers_1x3_shard1_replica2] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/tempDir-001/node1/c8n_vers_1x3_shard1_replica2],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/tempDir-001/node1/./c8n_vers_1x3_shard1_replica2/data/]
[junit4] 2> 1373574 INFO (qtp1869646694-8786) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.c.SolrCore [[c8n_vers_1x3_shard1_replica3] ] Opening new SolrCore at
[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/tempDir-001/node3/c8n_vers_1x3_shard1_replica3],
dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.DistributedVersionInfoTest_91AD62725D2749CB-001/tempDir-001/node3/./c8n_vers_1x3_shard1_replica3/data/]
[junit4] 2> 1373580 INFO (qtp50002674-8802) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1373580 INFO (qtp50002674-8802) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1373580 INFO (qtp50002674-8802) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@11dc8aa0[c8n_vers_1x3_shard1_replica1] main]
[junit4] 2> 1373581 INFO (qtp50002674-8802) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1373581 INFO (qtp50002674-8802) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1373581 INFO (qtp50002674-8802) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1373581 INFO
(searcherExecutor-5037-thread-1-processing-n:127.0.0.1:38974_solr
x:c8n_vers_1x3_shard1_replica1 s:shard1 c:c8n_vers_1x3 r:core_node2)
[n:127.0.0.1:38974_solr c:c8n_vers_1x3 s:shard1 r:core_node2
x:c8n_vers_1x3_shard1_replica1] o.a.s.c.SolrCore [c8n_vers_1x3_shard1_replica1]
Registered new searcher Searcher@11dc8aa0[c8n_vers_1x3_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C1)))}
[junit4] 2> 1373582 INFO (qtp243354485-8806) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1373582 INFO (qtp243354485-8806) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1373583 INFO (qtp243354485-8806) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@7fc84793[c8n_vers_1x3_shard1_replica2] main]
[junit4] 2> 1373583 INFO (qtp243354485-8806) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 1373584 INFO (qtp243354485-8806) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 1373584 INFO (qtp243354485-8806) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1373584 INFO
(searcherExecutor-5038-thread-1-processing-n:127.0.0.1:33992_solr
x:c8n_vers_1x3_shard1_replica2 s:shard1 c:c8n_vers_1x3 r:core_node1)
[n:127.0.0.1:33992_solr c:c8n_vers_1x3 s:shard1 r:core_node1
x:c8n_vers_1x3_shard1_replica2] o.a.s.c.SolrCore [c8n_vers_1x3_shard1_replica2]
Registered new searcher Searcher@7fc84793[c8n_vers_1x3_shard1_replica2]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C1)))}
[junit4] 2> 1373584 INFO (qtp50002674-8802) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1373585 INFO (qtp50002674-8802) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@4cb4ed79[c8n_vers_1x3_shard1_replica1] main]
[junit4] 2> 1373585 INFO
(searcherExecutor-5037-thread-1-processing-n:127.0.0.1:38974_solr
x:c8n_vers_1x3_shard1_replica1 s:shard1 c:c8n_vers_1x3 r:core_node2)
[n:127.0.0.1:38974_solr c:c8n_vers_1x3 s:shard1 r:core_node2
x:c8n_vers_1x3_shard1_replica1] o.a.s.c.SolrCore [c8n_vers_1x3_shard1_replica1]
Registered new searcher Searcher@4cb4ed79[c8n_vers_1x3_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.5.0):C1)))}
[junit4] 2> 1373585 INFO (qtp50002674-8802) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.c.SolrCore [c8n_vers_1x3_shard1_replica1] CLOSING SolrCore
org.apache.solr.core.SolrCore@23a78800
[junit4] 2> 1373586 INFO (qtp50002674-8802) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.m.SolrMetricManager Closing metric reporters for:
solr.core.c8n_vers_1x3.shard1.replica1
[junit4] 2> 1373586 INFO (qtp50002674-8802) [n:127.0.0.1:38974_solr
c:c8n_vers_1x3 s:shard1 r:core_node2 x:c8n_vers_1x3_shard1_replica1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={core=c8n_vers_1x3_shard1_replica1&qt=/admin/cores&action=RELOAD&wt=javabin&version=2}
status=0 QTime=50
[junit4] 2> 1373587 INFO (qtp1869646694-8786) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1373587 INFO (qtp1869646694-8786) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1373587 INFO (qtp1869646694-8786) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3 s:shard1 r:core_node3 x:c8n_vers_1x3_shard1_replica3]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@672567c4[c8n_vers_1x3_shard1_replica3] main]
[junit4] 2> 1373587 INFO (qtp243354485-8806) [n:127.0.0.1:33992_solr
c:c8n_vers_1x3 s:shard1 r:core_node1 x:c8n_vers_1x3_shard1_replica2]
o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 1373587 INFO (qtp1869646694-8786) [n:127.0.0.1:33323_solr
c:c8n_vers_1x3
[...truncated too long message...]
[junit4] 2> 1396264 INFO (coreCloseExecutor-5098-thread-1)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1 r:core_node3
x:c8n_vers_1x3_shard1_replica3] o.a.s.c.SolrCore [c8n_vers_1x3_shard1_replica3]
CLOSING SolrCore org.apache.solr.core.SolrCore@598caf92
[junit4] 2> 1396265 INFO (coreCloseExecutor-5099-thread-1)
[n:127.0.0.1:33992_solr c:c8n_vers_1x3 s:shard1 r:core_node1
x:c8n_vers_1x3_shard1_replica2] o.a.s.c.SolrCore [c8n_vers_1x3_shard1_replica2]
CLOSING SolrCore org.apache.solr.core.SolrCore@3dd65334
[junit4] 2> 1396265 INFO (coreCloseExecutor-5097-thread-1)
[n:127.0.0.1:38974_solr c:c8n_vers_1x3 s:shard1 r:core_node2
x:c8n_vers_1x3_shard1_replica1] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.c8n_vers_1x3.shard1.replica1
[junit4] 2> 1396266 INFO
(zkCallback-1410-thread-3-processing-n:127.0.0.1:33992_solr)
[n:127.0.0.1:33992_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/c8n_vers_1x3/state.json] for collection [c8n_vers_1x3] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1396266 INFO
(zkCallback-1412-thread-1-processing-n:127.0.0.1:33323_solr)
[n:127.0.0.1:33323_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/c8n_vers_1x3/state.json] for collection [c8n_vers_1x3] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1396266 INFO
(zkCallback-1411-thread-1-processing-n:127.0.0.1:38974_solr)
[n:127.0.0.1:38974_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/c8n_vers_1x3/state.json] for collection [c8n_vers_1x3] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 1396267 INFO (coreCloseExecutor-5099-thread-1)
[n:127.0.0.1:33992_solr c:c8n_vers_1x3 s:shard1 r:core_node1
x:c8n_vers_1x3_shard1_replica2] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.c8n_vers_1x3.shard1.replica2
[junit4] 2> 1396267 INFO (coreCloseExecutor-5098-thread-1)
[n:127.0.0.1:33323_solr c:c8n_vers_1x3 s:shard1 r:core_node3
x:c8n_vers_1x3_shard1_replica3] o.a.s.m.SolrMetricManager Closing metric
reporters for: solr.core.c8n_vers_1x3.shard1.replica3
[junit4] 2> 1396267 INFO
(zkCallback-1410-thread-2-processing-n:127.0.0.1:33992_solr)
[n:127.0.0.1:33992_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 1396267 INFO
(zkCallback-1412-thread-2-processing-n:127.0.0.1:33323_solr)
[n:127.0.0.1:33323_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (2)
[junit4] 2> 1396278 INFO (jetty-launcher-1396-thread-2) [ ]
o.a.s.c.Overseer Overseer
(id=97389758172168199-127.0.0.1:33992_solr-n_0000000000) closing
[junit4] 2> 1396278 INFO
(OverseerStateUpdate-97389758172168199-127.0.0.1:33992_solr-n_0000000000)
[n:127.0.0.1:33992_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:33992_solr
[junit4] 2> 1397767 WARN
(zkCallback-1411-thread-2-processing-n:127.0.0.1:38974_solr)
[n:127.0.0.1:38974_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1397767 WARN
(zkCallback-1411-thread-1-processing-n:127.0.0.1:38974_solr)
[n:127.0.0.1:38974_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for
/collections/c8n_vers_1x3/state.json]
[junit4] 2> 1397767 INFO (jetty-launcher-1396-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1397767 INFO (jetty-launcher-1396-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@fbddd8d{/solr,null,UNAVAILABLE}
[junit4] 2> 1397780 WARN
(zkCallback-1410-thread-2-processing-n:127.0.0.1:33992_solr)
[n:127.0.0.1:33992_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1397780 WARN
(zkCallback-1412-thread-2-processing-n:127.0.0.1:33323_solr)
[n:127.0.0.1:33323_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 1397780 INFO (jetty-launcher-1396-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1397780 INFO (jetty-launcher-1396-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@1812a838{/solr,null,UNAVAILABLE}
[junit4] 2> 1397780 INFO (jetty-launcher-1396-thread-3) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
[junit4] 2> 1397780 INFO (jetty-launcher-1396-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@3f3cb038{/solr,null,UNAVAILABLE}
[junit4] 2> 1397781 INFO
(SUITE-DistributedVersionInfoTest-seed#[91AD62725D2749CB]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:46467 46467
[junit4] 2> 1412798 INFO (Thread-2245) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:46467 46467
[junit4] 2> 1412799 WARN (Thread-2245) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 4 /solr/clusterprops.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 3 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/collections/c8n_vers_1x3/state.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 19 /solr/overseer/collection-queue-work
[junit4] 2> 10 /solr/overseer/queue
[junit4] 2> 4 /solr/live_nodes
[junit4] 2> 4 /solr/collections
[junit4] 2> 3 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> 1412799 INFO
(SUITE-DistributedVersionInfoTest-seed#[91AD62725D2749CB]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62):
{_version_=PostingsFormat(name=Memory doPackFST= false),
a_t=PostingsFormat(name=LuceneFixedGap),
id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128)))},
docValues:{}, maxPointsInLeafNode=140, maxMBSortInHeap=5.047484463348296,
sim=RandomSimilarity(queryNorm=true,coord=crazy): {}, locale=ru-RU,
timezone=Pacific/Gambier
[junit4] 2> NOTE: Linux 4.4.0-53-generic amd64/Oracle Corporation
1.8.0_121 (64-bit)/cpus=12,threads=1,free=188839648,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [URLClassifyProcessorTest,
HLLSerializationTest, SpellCheckCollatorTest, HdfsRecoveryZkTest,
ShardSplitTest, TestDistributedGrouping, TestDynamicFieldCollectionResource,
TestSizeLimitedDistributedMap, CloneFieldUpdateProcessorFactoryTest,
TestBinaryResponseWriter, ZkStateReaderTest, JsonLoaderTest,
TestCSVResponseWriter, TestHashPartitioner, TestManagedSchema,
HdfsThreadLeakTest, TestWordDelimiterFilterFactory,
TestLMDirichletSimilarityFactory, BufferStoreTest,
TestDocBasedVersionConstraints, TriLevelCompositeIdRoutingTest,
FacetPivotSmallTest, TestOrdValues, TestQueryUtils, SpatialFilterTest,
TestSchemaNameResource, TestRuleBasedAuthorizationPlugin, ZkCLITest,
TestSolrFieldCacheMBean, TestSmileRequest,
ClassificationUpdateProcessorFactoryTest, PeerSyncReplicationTest,
RollingRestartTest, TestBM25SimilarityFactory,
SimpleCollectionCreateDeleteTest, TestLeaderElectionWithEmptyReplica,
FileUtilsTest, TestFiltering, TestSuggestSpellingConverter,
TestHighFrequencyDictionaryFactory, PKIAuthenticationIntegrationTest,
MetricUtilsTest, HdfsBasicDistributedZkTest, TemplateUpdateProcessorTest,
IndexSchemaRuntimeFieldTest, TestBlendedInfixSuggestions, TestSystemIdResolver,
TestSimpleTrackingShardHandler, SolrMetricsIntegrationTest, SortByFunctionTest,
TestDistributedStatsComponentCardinality, SpatialRPTFieldTypeTest,
VersionInfoTest, TestCloudInspectUtil, SampleTest,
DistributedFacetExistsSmallTest, TestCharFilters,
UUIDUpdateProcessorFallbackTest, DocValuesTest, SpellCheckComponentTest,
TestRTGBase, TestElisionMultitermQuery, TestDocTermOrds, TestQueryTypes,
ConvertedLegacyTest, CopyFieldTest, OverriddenZkACLAndCredentialsProvidersTest,
TestBlobHandler, DistributedIntervalFacetingTest, SliceStateTest,
DateMathParserTest, DistribJoinFromCollectionTest, ChaosMonkeySafeLeaderTest,
TestSchemaManager, TestDownShardTolerantSearch, TestPerFieldSimilarityClassic,
CdcrReplicationHandlerTest, TestCustomSort, HdfsWriteToMultipleCollectionsTest,
TestGraphTermsQParserPlugin, TestReplicaProperties, AddBlockUpdateTest,
ZkControllerTest, SolrIndexConfigTest, TestQuerySenderListener,
DataDrivenBlockJoinTest, SpatialHeatmapFacetsTest,
DistributedFacetPivotSmallAdvancedTest, StatsReloadRaceTest,
DocumentBuilderTest, BaseCdcrDistributedZkTest, OpenCloseCoreStressTest,
TestUpdate, TestSubQueryTransformerCrossCore, ResponseHeaderTest,
MetricsHandlerTest, RuleEngineTest, ChangedSchemaMergeTest, TestStressVersions,
SuggesterFSTTest, TestSchemaVersionResource, TestTrie, LoggingHandlerTest,
SolrInfoMBeanTest, MoreLikeThisHandlerTest, TestSolrIndexConfig,
SchemaVersionSpecificBehaviorTest, TestFaceting, TestRecovery,
DistributedSpellCheckComponentTest, TestRealTimeGet, TestStressReorder,
TestReload, HardAutoCommitTest, DistributedTermsComponentTest, TestRangeQuery,
TestDocumentBuilder, SystemInfoHandlerTest, CursorPagingTest,
AliasIntegrationTest, AssignTest, CdcrBootstrapTest, CollectionsAPISolrJTest,
DistributedQueueTest, DistributedVersionInfoTest]
[junit4] Completed [494/682 (1!)] on J1 in 49.11s, 1 test, 1 error <<<
FAILURES!
[...truncated 63082 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
