Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/491/
1 tests failed.
FAILED:
junit.framework.TestSuite.org.apache.solr.client.solrj.impl.CloudSolrClientTest
Error Message:
1 thread leaked from SUITE scope at
org.apache.solr.client.solrj.impl.CloudSolrClientTest: 1) Thread[id=486,
name=OverseerHdfsCoreFailoverThread-96791758873690121-127.0.0.1:36693_solr-n_0000000002,
state=TIMED_WAITING, group=Overseer Hdfs SolrCore Failover Thread.] at
java.lang.Thread.sleep(Native Method) at
org.apache.solr.cloud.OverseerAutoReplicaFailoverThread.run(OverseerAutoReplicaFailoverThread.java:137)
at java.lang.Thread.run(Thread.java:745)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.client.solrj.impl.CloudSolrClientTest:
1) Thread[id=486,
name=OverseerHdfsCoreFailoverThread-96791758873690121-127.0.0.1:36693_solr-n_0000000002,
state=TIMED_WAITING, group=Overseer Hdfs SolrCore Failover Thread.]
at java.lang.Thread.sleep(Native Method)
at
org.apache.solr.cloud.OverseerAutoReplicaFailoverThread.run(OverseerAutoReplicaFailoverThread.java:137)
at java.lang.Thread.run(Thread.java:745)
at __randomizedtesting.SeedInfo.seed([3E51B12B236F2E9A]:0)
Build Log:
[...truncated 13163 lines...]
[junit4] Suite: org.apache.solr.client.solrj.impl.CloudSolrClientTest
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.CloudSolrClientTest_3E51B12B236F2E9A-001/init-core-data-001
[junit4] 2> 39390 INFO
(SUITE-CloudSolrClientTest-seed#[3E51B12B236F2E9A]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 39390 INFO
(SUITE-CloudSolrClientTest-seed#[3E51B12B236F2E9A]-worker) [ ]
o.a.s.c.MiniSolrCloudCluster Starting cluster of 3 servers in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.CloudSolrClientTest_3E51B12B236F2E9A-001/tempDir-001
[junit4] 2> 39391 INFO
(SUITE-CloudSolrClientTest-seed#[3E51B12B236F2E9A]-worker) [ ]
o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 39392 INFO (Thread-68) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 39392 INFO (Thread-68) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 39492 INFO
(SUITE-CloudSolrClientTest-seed#[3E51B12B236F2E9A]-worker) [ ]
o.a.s.c.ZkTestServer start zk server on port:45966
[junit4] 2> 39524 INFO (jetty-launcher-57-thread-2) [ ] o.e.j.s.Server
jetty-9.3.8.v20160314
[junit4] 2> 39531 INFO (jetty-launcher-57-thread-3) [ ] o.e.j.s.Server
jetty-9.3.8.v20160314
[junit4] 2> 39535 INFO (jetty-launcher-57-thread-1) [ ] o.e.j.s.Server
jetty-9.3.8.v20160314
[junit4] 2> 39550 INFO (jetty-launcher-57-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@79e224ab{/solr,null,AVAILABLE}
[junit4] 2> 39550 INFO (jetty-launcher-57-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@23fb0125{/solr,null,AVAILABLE}
[junit4] 2> 39553 INFO (jetty-launcher-57-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@77d5457e{/solr,null,AVAILABLE}
[junit4] 2> 39561 INFO (jetty-launcher-57-thread-3) [ ]
o.e.j.s.ServerConnector Started ServerConnector@4ce02542{SSL,[ssl,
http/1.1]}{127.0.0.1:49012}
[junit4] 2> 39561 INFO (jetty-launcher-57-thread-3) [ ] o.e.j.s.Server
Started @41794ms
[junit4] 2> 39561 INFO (jetty-launcher-57-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=49012}
[junit4] 2> 39562 ERROR (jetty-launcher-57-thread-3) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 39562 INFO (jetty-launcher-57-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.3.0
[junit4] 2> 39562 INFO (jetty-launcher-57-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 39562 INFO (jetty-launcher-57-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 39562 INFO (jetty-launcher-57-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-10-20T00:57:11.811Z
[junit4] 2> 39563 INFO (jetty-launcher-57-thread-2) [ ]
o.e.j.s.ServerConnector Started ServerConnector@ac5cd38{SSL,[ssl,
http/1.1]}{127.0.0.1:36693}
[junit4] 2> 39563 INFO (jetty-launcher-57-thread-2) [ ] o.e.j.s.Server
Started @41796ms
[junit4] 2> 39563 INFO (jetty-launcher-57-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=36693}
[junit4] 2> 39565 INFO (jetty-launcher-57-thread-1) [ ]
o.e.j.s.ServerConnector Started ServerConnector@2ae343e0{SSL,[ssl,
http/1.1]}{127.0.0.1:53827}
[junit4] 2> 39565 INFO (jetty-launcher-57-thread-1) [ ] o.e.j.s.Server
Started @41798ms
[junit4] 2> 39565 INFO (jetty-launcher-57-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=53827}
[junit4] 2> 39565 ERROR (jetty-launcher-57-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 39566 INFO (jetty-launcher-57-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.3.0
[junit4] 2> 39566 INFO (jetty-launcher-57-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 39566 INFO (jetty-launcher-57-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 39566 INFO (jetty-launcher-57-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-10-20T00:57:11.815Z
[junit4] 2> 39566 ERROR (jetty-launcher-57-thread-2) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 39566 INFO (jetty-launcher-57-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
6.3.0
[junit4] 2> 39566 INFO (jetty-launcher-57-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 39566 INFO (jetty-launcher-57-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 39566 INFO (jetty-launcher-57-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2016-10-20T00:57:11.815Z
[junit4] 2> 39577 INFO (jetty-launcher-57-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 39590 INFO (jetty-launcher-57-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 39595 INFO (jetty-launcher-57-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 39596 INFO (jetty-launcher-57-thread-1) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 39596 INFO (jetty-launcher-57-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45966/solr
[junit4] 2> 39608 INFO (jetty-launcher-57-thread-3) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 39617 INFO (jetty-launcher-57-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45966/solr
[junit4] 2> 39626 INFO (jetty-launcher-57-thread-2) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 39630 INFO (jetty-launcher-57-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:45966/solr
[junit4] 2> 39638 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 0x157df9784da0005, 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> 39648 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 0x157df9784da0007, 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> 39681 INFO (jetty-launcher-57-thread-3)
[n:127.0.0.1:49012_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:49012_solr
[junit4] 2> 39681 INFO (jetty-launcher-57-thread-1)
[n:127.0.0.1:53827_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:53827_solr
[junit4] 2> 39682 INFO (jetty-launcher-57-thread-3)
[n:127.0.0.1:49012_solr ] o.a.s.c.Overseer Overseer
(id=96791758873690120-127.0.0.1:49012_solr-n_0000000000) starting
[junit4] 2> 39684 INFO
(zkCallback-72-thread-1-processing-n:127.0.0.1:49012_solr)
[n:127.0.0.1:49012_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 39690 INFO
(zkCallback-70-thread-1-processing-n:127.0.0.1:53827_solr)
[n:127.0.0.1:53827_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 39699 INFO
(zkCallback-73-thread-1-processing-n:127.0.0.1:36693_solr)
[n:127.0.0.1:36693_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 39702 INFO (jetty-launcher-57-thread-1)
[n:127.0.0.1:53827_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.CloudSolrClientTest_3E51B12B236F2E9A-001/tempDir-001/node1/.
[junit4] 2> 39704 INFO (jetty-launcher-57-thread-2)
[n:127.0.0.1:36693_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:36693_solr
[junit4] 2> 39705 INFO
(zkCallback-70-thread-1-processing-n:127.0.0.1:53827_solr)
[n:127.0.0.1:53827_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 39706 INFO
(zkCallback-73-thread-1-processing-n:127.0.0.1:36693_solr)
[n:127.0.0.1:36693_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 39724 INFO
(zkCallback-72-thread-1-processing-n:127.0.0.1:49012_solr)
[n:127.0.0.1:49012_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (2)
[junit4] 2> 39724 INFO (jetty-launcher-57-thread-2)
[n:127.0.0.1:36693_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.CloudSolrClientTest_3E51B12B236F2E9A-001/tempDir-001/node2/.
[junit4] 2> 39738 INFO (jetty-launcher-57-thread-3)
[n:127.0.0.1:49012_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:49012_solr
[junit4] 2> 39739 INFO
(zkCallback-73-thread-1-processing-n:127.0.0.1:36693_solr)
[n:127.0.0.1:36693_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 39739 INFO
(zkCallback-70-thread-1-processing-n:127.0.0.1:53827_solr)
[n:127.0.0.1:53827_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 39740 INFO
(zkCallback-72-thread-1-processing-n:127.0.0.1:49012_solr)
[n:127.0.0.1:49012_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (2) -> (3)
[junit4] 2> 39752 INFO (jetty-launcher-57-thread-3)
[n:127.0.0.1:49012_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.CloudSolrClientTest_3E51B12B236F2E9A-001/tempDir-001/node3/.
[junit4] 2> 39776 INFO
(SUITE-CloudSolrClientTest-seed#[3E51B12B236F2E9A]-worker) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 39841 INFO (qtp215629332-279) [n:127.0.0.1:49012_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=1&collection.configName=conf&name=collection1&action=CREATE&numShards=2&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 39844 INFO
(OverseerThreadFactory-100-thread-1-processing-n:127.0.0.1:49012_solr)
[n:127.0.0.1:49012_solr ] o.a.s.c.CreateCollectionCmd Create collection
collection1
[junit4] 2> 39974 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=collection1_shard2_replica1&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2
[junit4] 2> 40020 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=collection1_shard1_replica1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2
[junit4] 2> 40126 INFO
(zkCallback-73-thread-1-processing-n:127.0.0.1:36693_solr)
[n:127.0.0.1:36693_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 40126 INFO
(zkCallback-72-thread-1-processing-n:127.0.0.1:49012_solr)
[n:127.0.0.1:49012_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 40988 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.3.0
[junit4] 2> 41002 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.s.IndexSchema
[collection1_shard2_replica1] Schema name=test
[junit4] 2> 41035 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 6.3.0
[junit4] 2> 41052 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.s.IndexSchema
[collection1_shard1_replica1] Schema name=test
[junit4] 2> 41108 WARN (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.s.IndexSchema
[collection1_shard2_replica1] default search field in schema is text. WARNING:
Deprecated, please use 'df' on request instead.
[junit4] 2> 41116 WARN (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.s.IndexSchema
Field lowerfilt1and2 is not multivalued and destination for multiple copyFields
(2)
[junit4] 2> 41116 WARN (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.s.IndexSchema
Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 41117 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 41125 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 x:collection1_shard2_replica1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard2_replica1' using configuration from
collection collection1
[junit4] 2> 41126 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.c.SolrCore [[collection1_shard2_replica1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.CloudSolrClientTest_3E51B12B236F2E9A-001/tempDir-001/node3/collection1_shard2_replica1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.CloudSolrClientTest_3E51B12B236F2E9A-001/tempDir-001/node3/./collection1_shard2_replica1/data/]
[junit4] 2> 41155 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 41155 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 41156 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 41156 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 41156 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@3a45f5b4[collection1_shard2_replica1] main]
[junit4] 2> 41157 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 41158 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 41158 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 41158 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1548668143829778432
[junit4] 2> 41165 INFO
(searcherExecutor-103-thread-1-processing-n:127.0.0.1:49012_solr
x:collection1_shard2_replica1 s:shard2 c:collection1 r:core_node1)
[n:127.0.0.1:49012_solr c:collection1 s:shard2 r:core_node1
x:collection1_shard2_replica1] o.a.s.c.SolrCore [collection1_shard2_replica1]
Registered new searcher Searcher@3a45f5b4[collection1_shard2_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 41166 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 41167 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 41167 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:49012/solr/collection1_shard2_replica1/
[junit4] 2> 41167 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 41167 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.c.SyncStrategy https://127.0.0.1:49012/solr/collection1_shard2_replica1/
has no replicas
[junit4] 2> 41173 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:49012/solr/collection1_shard2_replica1/ shard2
[junit4] 2> 41197 WARN (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.s.IndexSchema
[collection1_shard1_replica1] default search field in schema is text. WARNING:
Deprecated, please use 'df' on request instead.
[junit4] 2> 41198 WARN (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.s.IndexSchema
Field lowerfilt1and2 is not multivalued and destination for multiple copyFields
(2)
[junit4] 2> 41198 WARN (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.s.IndexSchema
Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 41199 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.s.IndexSchema
Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 41200 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 x:collection1_shard1_replica1] o.a.s.c.CoreContainer
Creating SolrCore 'collection1_shard1_replica1' using configuration from
collection collection1
[junit4] 2> 41200 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.SolrCore [[collection1_shard1_replica1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.CloudSolrClientTest_3E51B12B236F2E9A-001/tempDir-001/node2/collection1_shard1_replica1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-solrj/test/J2/temp/solr.client.solrj.impl.CloudSolrClientTest_3E51B12B236F2E9A-001/tempDir-001/node2/./collection1_shard1_replica1/data/]
[junit4] 2> 41210 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 41210 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 41211 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 41211 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 41212 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@3ae7e460[collection1_shard1_replica1] main]
[junit4] 2> 41213 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 41214 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 41214 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 41214 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using
new clock 1548668143888498688
[junit4] 2> 41223 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 41223 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 41223 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.SyncStrategy Sync replicas to
https://127.0.0.1:36693/solr/collection1_shard1_replica1/
[junit4] 2> 41223 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 41223 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.SyncStrategy https://127.0.0.1:36693/solr/collection1_shard1_replica1/
has no replicas
[junit4] 2> 41227 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
https://127.0.0.1:36693/solr/collection1_shard1_replica1/ shard1
[junit4] 2> 41227 INFO
(searcherExecutor-108-thread-1-processing-n:127.0.0.1:36693_solr
x:collection1_shard1_replica1 s:shard1 c:collection1 r:core_node2)
[n:127.0.0.1:36693_solr c:collection1 s:shard1 r:core_node2
x:collection1_shard1_replica1] o.a.s.c.SolrCore [collection1_shard1_replica1]
Registered new searcher Searcher@3ae7e460[collection1_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 41331 INFO
(zkCallback-73-thread-1-processing-n:127.0.0.1:36693_solr)
[n:127.0.0.1:36693_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 41331 INFO
(zkCallback-72-thread-2-processing-n:127.0.0.1:49012_solr)
[n:127.0.0.1:49012_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 41374 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 41378 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=collection1_shard2_replica1&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2}
status=0 QTime=1404
[junit4] 2> 41379 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 41383 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=collection1_shard1_replica1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2}
status=0 QTime=1362
[junit4] 2> 41389 INFO (qtp215629332-279) [n:127.0.0.1:49012_solr ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 41493 INFO
(zkCallback-72-thread-3-processing-n:127.0.0.1:49012_solr)
[n:127.0.0.1:49012_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 41493 INFO
(zkCallback-73-thread-1-processing-n:127.0.0.1:36693_solr)
[n:127.0.0.1:36693_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json] for collection [collection1] has
occurred - updating... (live nodes size: [3])
[junit4] 2> 41848 INFO
(OverseerCollectionConfigSetProcessor-96791758873690120-127.0.0.1:49012_solr-n_0000000000)
[n:127.0.0.1:49012_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> 42389 INFO (qtp215629332-279) [n:127.0.0.1:49012_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=1&collection.configName=conf&name=collection1&action=CREATE&numShards=2&wt=javabin&version=2}
status=0 QTime=2548
[junit4] 2> 42390 INFO
(SUITE-CloudSolrClientTest-seed#[3E51B12B236F2E9A]-worker) [ ]
o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection:
collection1 failOnTimeout:true timeout (sec):30
[junit4] 2> 42391 INFO
(SUITE-CloudSolrClientTest-seed#[3E51B12B236F2E9A]-worker) [ ]
o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
[junit4] 2> 42407 INFO
(TEST-CloudSolrClientTest.testRouting-seed#[3E51B12B236F2E9A]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testRouting
[junit4] 2> 42456 INFO (qtp215629332-271) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr
path=/update
params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:36693/solr/collection1_shard1_replica1/&wt=javabin&version=2}{deleteByQuery=*:*
(-1548668145188732928)} 0 2
[junit4] 2> 42467 INFO (qtp901936033-261) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 42467 INFO (qtp901936033-261) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 42468 INFO (qtp901936033-261) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 42470 INFO (qtp901936033-261) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:36693/solr/collection1_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 2
[junit4] 2> 42475 INFO (qtp215629332-285) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 42475 INFO (qtp215629332-285) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 42477 INFO (qtp215629332-285) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 42477 INFO (qtp215629332-285) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:36693/solr/collection1_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 1
[junit4] 2> 42477 INFO (qtp901936033-274) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1] webapp=/solr
path=/update
params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
(-1548668145173004288),commit=} 0 39
[junit4] 2> 42495 INFO (qtp215629332-284) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr
path=/update params={_stateVer_=collection1:3&wt=javabin&version=2}{add=[2
(1548668145222287360)]} 0 8
[junit4] 2> 42496 INFO (qtp901936033-265) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1] webapp=/solr
path=/update params={_stateVer_=collection1:3&wt=javabin&version=2}{add=[0
(1548668145223335936)]} 0 9
[junit4] 2> 42538 INFO (qtp215629332-271) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 42538 INFO (qtp901936033-274) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 42540 INFO (qtp215629332-271) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@5db10089
[junit4] 2> 42540 INFO (qtp901936033-274) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@74f2f7cd
[junit4] 2> 42571 INFO (qtp215629332-271) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@634bf119[collection1_shard2_replica1] main]
[junit4] 2> 42571 INFO (qtp901936033-274) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@4835186a[collection1_shard1_replica1] main]
[junit4] 2> 42571 INFO (qtp901936033-274) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 42571 INFO
(searcherExecutor-108-thread-1-processing-n:127.0.0.1:36693_solr
x:collection1_shard1_replica1 s:shard1 c:collection1 r:core_node2)
[n:127.0.0.1:36693_solr c:collection1 s:shard1 r:core_node2
x:collection1_shard1_replica1] o.a.s.c.SolrCore [collection1_shard1_replica1]
Registered new searcher Searcher@4835186a[collection1_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C1)))}
[junit4] 2> 42572 INFO (qtp901936033-274) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:49012/solr/collection1_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 34
[junit4] 2> 42573 INFO (qtp215629332-271) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 42573 INFO
(searcherExecutor-103-thread-1-processing-n:127.0.0.1:49012_solr
x:collection1_shard2_replica1 s:shard2 c:collection1 r:core_node1)
[n:127.0.0.1:49012_solr c:collection1 s:shard2 r:core_node1
x:collection1_shard2_replica1] o.a.s.c.SolrCore [collection1_shard2_replica1]
Registered new searcher Searcher@634bf119[collection1_shard2_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C1)))}
[junit4] 2> 42573 INFO (qtp215629332-271) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:49012/solr/collection1_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 35
[junit4] 2> 42574 INFO (qtp215629332-279) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr
path=/update
params={_stateVer_=collection1:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 75
[junit4] 2> 42591 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.S.Request [collection1_shard1_replica1] webapp=/solr path=/select
params={q=id:0&distrib=false&wt=javabin&version=2} hits=1 status=0 QTime=10
[junit4] 2> 42598 INFO (qtp215629332-284) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.c.S.Request [collection1_shard2_replica1] webapp=/solr path=/select
params={q=id:2&distrib=false&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 42602 INFO (qtp901936033-265) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1] webapp=/solr
path=/update params={_stateVer_=collection1:3&wt=javabin&version=2}{delete=[0
(-1548668145342873600)]} 0 0
[junit4] 2> 42602 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr
path=/update params={_stateVer_=collection1:3&wt=javabin&version=2}{delete=[2
(-1548668145342873600)]} 0 0
[junit4] 2> 42608 INFO (qtp215629332-285) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 42608 INFO (qtp215629332-285) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@5db10089
[junit4] 2> 42608 INFO (qtp901936033-272) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 42609 INFO (qtp901936033-272) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@74f2f7cd
[junit4] 2> 42610 INFO (qtp215629332-285) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@70f35f71[collection1_shard2_replica1] main]
[junit4] 2> 42610 INFO (qtp215629332-285) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 42610 INFO
(searcherExecutor-103-thread-1-processing-n:127.0.0.1:49012_solr
x:collection1_shard2_replica1 s:shard2 c:collection1 r:core_node1)
[n:127.0.0.1:49012_solr c:collection1 s:shard2 r:core_node1
x:collection1_shard2_replica1] o.a.s.c.SolrCore [collection1_shard2_replica1]
Registered new searcher Searcher@70f35f71[collection1_shard2_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 42610 INFO (qtp901936033-272) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@2bfaa1dd[collection1_shard1_replica1] main]
[junit4] 2> 42610 INFO (qtp901936033-272) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 42610 INFO
(searcherExecutor-108-thread-1-processing-n:127.0.0.1:36693_solr
x:collection1_shard1_replica1 s:shard1 c:collection1 r:core_node2)
[n:127.0.0.1:36693_solr c:collection1 s:shard1 r:core_node2
x:collection1_shard1_replica1] o.a.s.c.SolrCore [collection1_shard1_replica1]
Registered new searcher Searcher@2bfaa1dd[collection1_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 42610 INFO (qtp215629332-285) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:36693/solr/collection1_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 2
[junit4] 2> 42611 INFO (qtp901936033-272) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:36693/solr/collection1_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 2
[junit4] 2> 42611 INFO (qtp901936033-280) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1] webapp=/solr
path=/update
params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{commit=} 0 7
[junit4] 2> 42620 INFO (qtp901936033-274) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.S.Request [collection1_shard1_replica1] webapp=/solr path=/select
params={distrib=false&_stateVer_=collection1:3&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=https://127.0.0.1:36693/solr/collection1_shard1_replica1/&rows=10&version=2&q=*:*&NOW=1476925034863&isShard=true&wt=javabin}
hits=0 status=0 QTime=1
[junit4] 2> 42620 INFO (qtp215629332-278) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.c.S.Request [collection1_shard2_replica1] webapp=/solr path=/select
params={distrib=false&_stateVer_=collection1:3&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=https://127.0.0.1:49012/solr/collection1_shard2_replica1/&rows=10&version=2&q=*:*&NOW=1476925034863&isShard=true&wt=javabin}
hits=0 status=0 QTime=1
[junit4] 2> 42624 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.c.S.Request [collection1_shard2_replica1] webapp=/solr path=/select
params={q=*:*&_stateVer_=collection1:3&wt=javabin&version=2} hits=0 status=0
QTime=9
[junit4] 2> 42634 INFO
(TEST-CloudSolrClientTest.testRouting-seed#[3E51B12B236F2E9A]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 42643 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1] webapp=/solr
path=/update params={_stateVer_=collection1:3&wt=javabin&version=2}{add=[0
(1548668145384816640)]} 0 1
[junit4] 2> 42647 INFO (qtp215629332-285) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr
path=/update params={_stateVer_=collection1:3&wt=javabin&version=2}{add=[2
(1548668145389010944)]} 0 1
[junit4] 2> 42654 INFO (qtp901936033-280) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 42654 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 42654 INFO (qtp901936033-280) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@74f2f7cd
[junit4] 2> 42654 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@5db10089
[junit4] 2> 42658 INFO (qtp901936033-280) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@243b817e[collection1_shard1_replica1] main]
[junit4] 2> 42658 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@44b8fb9b[collection1_shard2_replica1] main]
[junit4] 2> 42658 INFO
(searcherExecutor-103-thread-1-processing-n:127.0.0.1:49012_solr
x:collection1_shard2_replica1 s:shard2 c:collection1 r:core_node1)
[n:127.0.0.1:49012_solr c:collection1 s:shard2 r:core_node1
x:collection1_shard2_replica1] o.a.s.c.SolrCore [collection1_shard2_replica1]
Registered new searcher Searcher@44b8fb9b[collection1_shard2_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.3.0):C1)))}
[junit4] 2> 42658 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 42658 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:49012/solr/collection1_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 4
[junit4] 2> 42658 INFO
(searcherExecutor-108-thread-1-processing-n:127.0.0.1:36693_solr
x:collection1_shard1_replica1 s:shard1 c:collection1 r:core_node2)
[n:127.0.0.1:36693_solr c:collection1 s:shard1 r:core_node2
x:collection1_shard1_replica1] o.a.s.c.SolrCore [collection1_shard1_replica1]
Registered new searcher Searcher@243b817e[collection1_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.3.0):C1)))}
[junit4] 2> 42658 INFO (qtp901936033-280) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 42658 INFO (qtp901936033-280) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:49012/solr/collection1_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 4
[junit4] 2> 42661 INFO (qtp215629332-278) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr
path=/update
params={_stateVer_=collection1:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
0 11
[junit4] 2> 42667 INFO (qtp901936033-265) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.S.Request [collection1_shard1_replica1] webapp=/solr path=/select
params={q=id:0&distrib=false&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 42673 INFO (qtp215629332-285) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.c.S.Request [collection1_shard2_replica1] webapp=/solr path=/select
params={q=id:2&distrib=false&wt=javabin&version=2} hits=1 status=0 QTime=0
[junit4] 2> 42685 INFO (qtp901936033-274) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.S.Request [collection1_shard1_replica1] webapp=/solr
path=/admin/mbeans
params={qt=/admin/mbeans&stats=true&cat=QUERYHANDLER&wt=javabin&version=2&key=standard}
status=0 QTime=1
[junit4] 2> 42696 INFO (qtp215629332-277) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.c.S.Request [collection1_shard2_replica1] webapp=/solr
path=/admin/mbeans
params={qt=/admin/mbeans&stats=true&cat=QUERYHANDLER&wt=javabin&version=2&key=standard}
status=0 QTime=0
[junit4] 2> 42697 INFO
(TEST-CloudSolrClientTest.testRouting-seed#[3E51B12B236F2E9A]) [ ]
o.a.s.c.s.i.CloudSolrClientTest Expected Slices [shard1:{
[junit4] 2> "range":"80000000-ffffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"collection1_shard1_replica1",
[junit4] 2> "base_url":"https://127.0.0.1:36693/solr";,
[junit4] 2> "node_name":"127.0.0.1:36693_solr",
[junit4] 2> "state":"active",
[junit4] 2> "leader":"true"}}}]
[junit4] 2> 42697 INFO
(TEST-CloudSolrClientTest.testRouting-seed#[3E51B12B236F2E9A]) [ ]
o.a.s.c.s.i.CloudSolrClientTest Expected Slices [shard2:{
[junit4] 2> "range":"0-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node1":{
[junit4] 2> "core":"collection1_shard2_replica1",
[junit4] 2> "base_url":"https://127.0.0.1:49012/solr";,
[junit4] 2> "node_name":"127.0.0.1:49012_solr",
[junit4] 2> "state":"active",
[junit4] 2> "leader":"true"}}}]
[junit4] 2> 42697 INFO
(TEST-CloudSolrClientTest.testRouting-seed#[3E51B12B236F2E9A]) [ ]
o.a.s.c.s.i.CloudSolrClientTest Expected Slices [shard2:{
[junit4] 2> "range":"0-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node1":{
[junit4] 2> "core":"collection1_shard2_replica1",
[junit4] 2> "base_url":"https://127.0.0.1:49012/solr";,
[junit4] 2> "node_name":"127.0.0.1:49012_solr",
[junit4] 2> "state":"active",
[junit4] 2> "leader":"true"}}}]
[junit4] 2> 42697 INFO
(TEST-CloudSolrClientTest.testRouting-seed#[3E51B12B236F2E9A]) [ ]
o.a.s.c.s.i.CloudSolrClientTest Expected Slices [shard1:{
[junit4] 2> "range":"80000000-ffffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"collection1_shard1_replica1",
[junit4] 2> "base_url":"https://127.0.0.1:36693/solr";,
[junit4] 2> "node_name":"127.0.0.1:36693_solr",
[junit4] 2> "state":"active",
[junit4] 2> "leader":"true"}}}]
[junit4] 2> 42700 INFO (qtp901936033-262) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.S.Request [collection1_shard1_replica1] webapp=/solr path=/select
params={q=*:*&_stateVer_=collection1:3&wt=javabin&version=2&_route_=4} hits=1
status=0 QTime=0
[junit4] 2> 42701 INFO
(TEST-CloudSolrClientTest.testRouting-seed#[3E51B12B236F2E9A]) [ ]
o.a.s.c.s.i.CloudSolrClientTest output:
{responseHeader={zkConnected=true,status=0,QTime=0},response={numFound=1,start=0,docs=[SolrDocument{id=0,
a_t=hello1, _version_=1548668145384816640, multiDefault=[muLti-Default],
intDefault=42, timestamp=Thu Oct 20 02:57:14 CEST 2016}]}}
[junit4] 2> 42704 INFO (qtp901936033-265) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.S.Request [collection1_shard1_replica1] webapp=/solr path=/select
params={q=*:*&_stateVer_=collection1:3&wt=javabin&version=2&_route_=1} hits=1
status=0 QTime=0
[junit4] 2> 42705 INFO
(TEST-CloudSolrClientTest.testRouting-seed#[3E51B12B236F2E9A]) [ ]
o.a.s.c.s.i.CloudSolrClientTest output:
{responseHeader={zkConnected=true,status=0,QTime=0},response={numFound=1,start=0,docs=[SolrDocument{id=0,
a_t=hello1, _version_=1548668145384816640, multiDefault=[muLti-Default],
intDefault=42, timestamp=Thu Oct 20 02:57:14 CEST 2016}]}}
[junit4] 2> 42707 INFO (qtp901936033-265) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.S.Request [collection1_shard1_replica1] webapp=/solr path=/select
params={q=*:*&_stateVer_=collection1:3&wt=javabin&version=2&_route_=1} hits=1
status=0 QTime=0
[junit4] 2> 42708 INFO
(TEST-CloudSolrClientTest.testRouting-seed#[3E51B12B236F2E9A]) [ ]
o.a.s.c.s.i.CloudSolrClientTest output:
{responseHeader={zkConnected=true,status=0,QTime=0},response={numFound=1,start=0,docs=[SolrDocument{id=0,
a_t=hello1, _version_=1548668145384816640, multiDefault=[muLti-Default],
intDefault=42, timestamp=Thu Oct 20 02:57:14 CEST 2016}]}}
[junit4] 2> 42710 INFO (qtp901936033-265) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.S.Request [collection1_shard1_replica1] webapp=/solr path=/select
params={q=*:*&_stateVer_=collection1:3&wt=javabin&version=2&_route_=4} hits=1
status=0 QTime=0
[junit4] 2> 42711 INFO
(TEST-CloudSolrClientTest.testRouting-seed#[3E51B12B236F2E9A]) [ ]
o.a.s.c.s.i.CloudSolrClientTest output:
{responseHeader={zkConnected=true,status=0,QTime=0},response={numFound=1,start=0,docs=[SolrDocument{id=0,
a_t=hello1, _version_=1548668145384816640, multiDefault=[muLti-Default],
intDefault=42, timestamp=Thu Oct 20 02:57:14 CEST 2016}]}}
[junit4] 2> 42713 INFO (qtp901936033-274) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.S.Request [collection1_shard1_replica1] webapp=/solr path=/select
params={q=*:*&_stateVer_=collection1:3&wt=javabin&version=2&_route_=0} hits=1
status=0 QTime=0
[junit4] 2> 42714 INFO
(TEST-CloudSolrClientTest.testRouting-seed#[3E51B12B236F2E9A]) [ ]
o.a.s.c.s.i.CloudSolrClientTest output:
{responseHeader={zkConnected=true,status=0,QTime=0},response={numFound=1,start=0,docs=[SolrDocument{id=0,
a_t=hello1, _version_=1548668145384816640, multiDefault=[muLti-Default],
intDefault=42, timestamp=Thu Oct 20 02:57:14 CEST 2016}]}}
[junit4] 2> 42720 INFO (qtp901936033-280) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.S.Request [collection1_shard1_replica1] webapp=/solr
path=/admin/mbeans
params={qt=/admin/mbeans&stats=true&cat=QUERYHANDLER&wt=javabin&version=2&key=standard}
status=0 QTime=0
[junit4] 2> 42725 INFO (qtp215629332-277) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.c.S.Request [collection1_shard2_replica1] webapp=/solr
path=/admin/mbeans
params={qt=/admin/mbeans&stats=true&cat=QUERYHANDLER&wt=javabin&version=2&key=standard}
status=0 QTime=0
[junit4] 2> 42726 INFO
(TEST-CloudSolrClientTest.testRouting-seed#[3E51B12B236F2E9A]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testRouting
[junit4] 2> 42746 INFO
(TEST-CloudSolrClientTest.customHttpClientTest-seed#[3E51B12B236F2E9A]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting customHttpClientTest
[junit4] 2> 42750 INFO (qtp215629332-277) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@7da893ca[collection1_shard2_replica1] realtime]
[junit4] 2> 42752 INFO (qtp901936033-261) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@582bd32c[collection1_shard1_replica1] realtime]
[junit4] 2> 42752 INFO (qtp901936033-261) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1] webapp=/solr
path=/update
params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:49012/solr/collection1_shard2_replica1/&wt=javabin&version=2}{deleteByQuery=*:*
(-1548668145499111424)} 0 1
[junit4] 2> 42755 INFO (qtp215629332-271) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 42755 INFO (qtp215629332-271) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@5db10089
[junit4] 2> 42756 INFO (qtp215629332-271) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@75737dda[collection1_shard2_replica1] main]
[junit4] 2> 42756 INFO (qtp215629332-271) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 42756 INFO
(searcherExecutor-103-thread-1-processing-n:127.0.0.1:49012_solr
x:collection1_shard2_replica1 s:shard2 c:collection1 r:core_node1)
[n:127.0.0.1:49012_solr c:collection1 s:shard2 r:core_node1
x:collection1_shard2_replica1] o.a.s.c.SolrCore [collection1_shard2_replica1]
Registered new searcher Searcher@75737dda[collection1_shard2_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 42756 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 42756 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@74f2f7cd
[junit4] 2> 42756 INFO (qtp215629332-271) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:49012/solr/collection1_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 1
[junit4] 2> 42757 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@4bb5ff1b[collection1_shard1_replica1] main]
[junit4] 2> 42757 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 42757 INFO
(searcherExecutor-108-thread-1-processing-n:127.0.0.1:36693_solr
x:collection1_shard1_replica1 s:shard1 c:collection1 r:core_node2)
[n:127.0.0.1:36693_solr c:collection1 s:shard1 r:core_node2
x:collection1_shard1_replica1] o.a.s.c.SolrCore [collection1_shard1_replica1]
Registered new searcher Searcher@4bb5ff1b[collection1_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 42757 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:49012/solr/collection1_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 42758 INFO (qtp215629332-277) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr
path=/update
params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
(-1548668145498062848),commit=} 0 9
[junit4] 2> 42759 INFO
(TEST-CloudSolrClientTest.customHttpClientTest-seed#[3E51B12B236F2E9A]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending customHttpClientTest
[junit4] 2> 42776 INFO
(TEST-CloudSolrClientTest.testParallelUpdateQTime-seed#[3E51B12B236F2E9A]) [
] o.a.s.SolrTestCaseJ4 ###Starting testParallelUpdateQTime
[junit4] 2> 42782 INFO (qtp901936033-262) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1] webapp=/solr
path=/update
params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:49012/solr/collection1_shard2_replica1/&wt=javabin&version=2}{deleteByQuery=*:*
(-1548668145531617280)} 0 0
[junit4] 2> 42785 INFO (qtp901936033-272) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 42785 INFO (qtp215629332-278) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 42785 INFO (qtp901936033-272) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 42785 INFO (qtp215629332-278) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 42785 INFO (qtp901936033-272) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 42785 INFO (qtp215629332-278) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 42785 INFO (qtp901936033-272) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:49012/solr/collection1_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 42785 INFO (qtp215629332-278) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:49012/solr/collection1_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 42786 INFO (qtp215629332-285) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr
path=/update
params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
(-1548668145529520128),commit=} 0 7
[junit4] 2> 42791 INFO (qtp215629332-279) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr
path=/update params={wt=javabin&version=2}{add=[1025 (1548668145540005888),
1027 (1548668145541054464), 1014 (1548668145541054465), 1014
(1548668145541054466), 1022 (1548668145541054467)]} 0 1
[junit4] 2> 42791 INFO (qtp901936033-280) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1] webapp=/solr
path=/update params={wt=javabin&version=2}{add=[1011 (1548668145540005888),
1021 (1548668145541054464), 1086 (1548668145541054465), 1052
(1548668145541054466), 1093 (1548668145541054467)]} 0 1
[junit4] 2> 42791 INFO
(TEST-CloudSolrClientTest.testParallelUpdateQTime-seed#[3E51B12B236F2E9A]) [
] o.a.s.SolrTestCaseJ4 ###Ending testParallelUpdateQTime
[junit4] 2> 42808 INFO
(TEST-CloudSolrClientTest.stateVersionParamTest-seed#[3E51B12B236F2E9A]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting stateVersionParamTest
[junit4] 2> 42817 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@8f62f33[collection1_shard2_replica1] realtime]
[junit4] 2> 42819 INFO (qtp901936033-274) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@71387b63[collection1_shard1_replica1] realtime]
[junit4] 2> 42819 INFO (qtp901936033-274) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1] webapp=/solr
path=/update
params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:49012/solr/collection1_shard2_replica1/&wt=javabin&version=2}{deleteByQuery=*:*
(-1548668145566220288)} 0 5
[junit4] 2> 42822 INFO (qtp901936033-261) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 42822 INFO (qtp901936033-261) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@74f2f7cd
[junit4] 2> 42823 INFO (qtp901936033-261) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@43cf9d73[collection1_shard1_replica1] main]
[junit4] 2> 42823 INFO (qtp215629332-284) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 42823 INFO (qtp215629332-284) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.SolrIndexWriter Calling setCommitData with
IW:org.apache.solr.update.SolrIndexWriter@5db10089
[junit4] 2> 42823 INFO (qtp901936033-261) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 42823 INFO
(searcherExecutor-108-thread-1-processing-n:127.0.0.1:36693_solr
x:collection1_shard1_replica1 s:shard1 c:collection1 r:core_node2)
[n:127.0.0.1:36693_solr c:collection1 s:shard1 r:core_node2
x:collection1_shard1_replica1] o.a.s.c.SolrCore [collection1_shard1_replica1]
Registered new searcher Searcher@43cf9d73[collection1_shard1_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 42823 INFO (qtp901936033-261) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:49012/solr/collection1_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 1
[junit4] 2> 42823 INFO (qtp215629332-284) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@58359be9[collection1_shard2_replica1] main]
[junit4] 2> 42824 INFO (qtp215629332-284) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 42824 INFO
(searcherExecutor-103-thread-1-processing-n:127.0.0.1:49012_solr
x:collection1_shard2_replica1 s:shard2 c:collection1 r:core_node1)
[n:127.0.0.1:49012_solr c:collection1 s:shard2 r:core_node1
x:collection1_shard2_replica1] o.a.s.c.SolrCore [collection1_shard2_replica1]
Registered new searcher Searcher@58359be9[collection1_shard2_replica1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 42824 INFO (qtp215629332-284) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:49012/solr/collection1_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 42824 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr
path=/update
params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
(-1548668145563074560),commit=} 0 13
[junit4] 2> 42844 INFO (qtp215629332-285) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.c.S.Request [collection1_shard2_replica1] webapp=/solr path=/select
params={q=*:*&distrib=false&fl=id&fl=score&shards.purpose=4&NOW=1476925035079&start=0&fsv=true&isShard=true&shard.url=https://127.0.0.1:49012/solr/collection1_shard2_replica1/&rows=10&wt=javabin&version=2}
hits=0 status=0 QTime=1
[junit4] 2> 42849 INFO (qtp901936033-262) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.S.Request [collection1_shard1_replica1] webapp=/solr path=/select
params={q=*:*&distrib=false&fl=id&fl=score&shards.purpose=4&NOW=1476925035079&start=0&fsv=true&isShard=true&shard.url=https://127.0.0.1:36693/solr/collection1_shard1_replica1/&rows=10&wt=javabin&version=2}
hits=0 status=0 QTime=0
[junit4] 2> 42850 INFO (qtp901936033-272) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.S.Request [collection1_shard1_replica1] webapp=/solr path=/select
params={q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=19
[junit4] 2> 42850 INFO
(TEST-CloudSolrClientTest.stateVersionParamTest-seed#[3E51B12B236F2E9A]) [ ]
o.a.s.c.s.i.CloudSolrClientTest should work query, result
{responseHeader={zkConnected=true,status=0,QTime=19},response={numFound=0,start=0,maxScore=0.0,docs=[]}}
[junit4] 2> 42860 INFO (qtp215629332-279) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.c.S.Request [collection1_shard2_replica1] webapp=/solr path=/select
params={distrib=false&_stateVer_=collection1:3&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=https://127.0.0.1:49012/solr/collection1_shard2_replica1/&rows=10&version=2&q=*:*&NOW=1476925035101&isShard=true&wt=javabin}
hits=0 status=0 QTime=0
[junit4] 2> 42860 INFO (qtp901936033-262) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.S.Request [collection1_shard1_replica1] webapp=/solr path=/select
params={distrib=false&_stateVer_=collection1:3&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=https://127.0.0.1:36693/solr/collection1_shard1_replica1/&rows=10&version=2&q=*:*&NOW=1476925035101&isShard=true&wt=javabin}
hits=0 status=0 QTime=0
[junit4] 2> 42861 INFO (qtp901936033-280) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.S.Request [collection1_shard1_replica1] webapp=/solr path=/select
params={q=*:*&_stateVer_=collection1:3&wt=javabin&version=2} hits=0 status=0
QTime=8
[junit4] 2> 42861 INFO
(TEST-CloudSolrClientTest.stateVersionParamTest-seed#[3E51B12B236F2E9A]) [ ]
o.a.s.c.s.i.CloudSolrClientTest 2nd query , result
{responseHeader={zkConnected=true,status=0,QTime=8},response={numFound=0,start=0,maxScore=0.0,docs=[]}}
[junit4] 2> 42865 INFO (qtp215629332-279) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.c.S.Request [collection1_shard2_replica1] webapp=/solr path=/select
params={distrib=false&_stateVer_=collection1:2&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=https://127.0.0.1:49012/solr/collection1_shard2_replica1/&rows=10&version=2&q=*:*&NOW=1476925035112&isShard=true&wt=javabin}
hits=0 status=0 QTime=0
[junit4] 2> 42865 INFO (qtp901936033-274) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.S.Request [collection1_shard1_replica1] webapp=/solr path=/select
params={distrib=false&_stateVer_=collection1:2&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&shard.url=https://127.0.0.1:36693/solr/collection1_shard1_replica1/&rows=10&version=2&q=*:*&NOW=1476925035112&isShard=true&wt=javabin}
hits=0 status=0 QTime=0
[junit4] 2> 42866 INFO (qtp901936033-265) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.c.S.Request [collection1_shard1_replica1] webapp=/solr path=/select
params={q=*:*&_stateVer_=collection1:2&wt=javabin&version=2} hits=0 status=0
QTime=2
[junit4] 2> 42866 INFO
(TEST-CloudSolrClientTest.stateVersionParamTest-seed#[3E51B12B236F2E9A]) [ ]
o.a.s.c.s.i.CloudSolrClientTest the node which does not serve this
collectionhttps://127.0.0.1:53827/solr
[junit4] 2> 42939 INFO
(TEST-CloudSolrClientTest.stateVersionParamTest-seed#[3E51B12B236F2E9A]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending stateVersionParamTest
[junit4] 2> 42957 INFO
(TEST-CloudSolrClientTest.testNonRetryableRequests-seed#[3E51B12B236F2E9A]) [
] o.a.s.SolrTestCaseJ4 ###Starting testNonRetryableRequests
[junit4] 2> 42963 INFO (qtp901936033-261) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1] webapp=/solr
path=/update
params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:49012/solr/collection1_shard2_replica1/&wt=javabin&version=2}{deleteByQuery=*:*
(-1548668145721409536)} 0 0
[junit4] 2> 42966 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 42966 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 42966 INFO (qtp901936033-262) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 42966 INFO (qtp901936033-262) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 42966 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 42966 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:49012/solr/collection1_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 42967 INFO (qtp901936033-262) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 42967 INFO (qtp901936033-262) [n:127.0.0.1:36693_solr
c:collection1 s:shard1 r:core_node2 x:collection1_shard1_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica1] webapp=/solr
path=/update
params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:49012/solr/collection1_shard2_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=}
0 0
[junit4] 2> 42969 INFO (qtp215629332-271) [n:127.0.0.1:49012_solr
c:collection1 s:shard2 r:core_node1 x:collection1_shard2_replica1]
o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica1] webapp=/solr
path=/update
params={_stateVer_=collection1:3&commit=true&wt=javabin&version=2}{deleteByQuery=*:*
(-1548668145719312384),commit=} 0 8
[junit4] 2> 42973 INFO
(TEST-CloudSolrClientTest.testNonRetryableRequests-seed#[3E51B12B236F2E9A]) [
] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 42978 INFO (qtp215629332-279) [n:127.0.0.1:49012_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
async=979ed142-8b09-40f8-9e53-3ea3bd0163a5&replicationFactor=3&collection.configName=conf&name=foo&action=CREATE&numShards=1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 42980 INFO (qtp215629332-279) [n:127.0.0.1:49012_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={async=979ed142-8b09-40f8-9e53-3ea3bd0163a5&replicationFactor=3&collection.configName=conf&name=foo&action=CREATE&numShards=1&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 42985 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=979ed142-8b09-40f8-9e53-3ea3bd0163a5&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 42985 INFO
(OverseerThreadFactory-100-thread-2-processing-n:127.0.0.1:49012_solr)
[n:127.0.0.1:49012_solr ] o.a.s.c.CreateCollectionCmd Create collection foo
[junit4] 2> 42985 INFO (qtp215629332-269) [n:127.0.0.1:49012_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=979ed142-8b09-40f8-9e53-3ea3bd0163a5&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 43197 INFO (qtp901936033-282) [n:127.0.0.1:36693_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={async=979ed142-8b09-40f8-9e53-3ea3bd0163a515301290969504939&qt=/admin/cores&collection.configName=conf&newCollection=true&name=foo_shard1_replica3&action=CREATE&numShards=1&collection=foo&shard=shard1&wt=javabin&version=2}
status=0 QTime=3
[junit4] 2> 43202 INFO (qtp215629332-278) [n:127.0.0.1:49012_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={async=979ed142-8b09-40f8-9e53-3ea3bd0163a515301290970274857&qt=/admin/cores&collection.configName=conf&newCollection=true&name=foo_shard1_replica1&action=CREATE&numShards=1&collection=foo&shard=shard1&wt=javabin&version=2}
status=0 QTime=8
[junit4] 2> 43202 INFO
(parallelCoreAdminExecutor-101-thread-1-processing-n:127.0.0.1:49012_solr
979ed142-8b09-40f8-9e53-3ea3bd0163a515301290970274857 CREATE)
[n:127.0.0.1:49012_solr ] o.a.s.h.a.CoreAdminOperation core create command
async=979ed142-8b09-40f8-9e53-3ea3bd0163a515301290970274857&qt=/admin/cores&collection.configName=conf&newCollection=true&name=foo_shard1_replica1&action=CREATE&numShards=1&collection=foo&shard=shard1&wt=javabin&version=2
[junit4] 2> 43202 INFO
(parallelCoreAdminExecutor-98-thread-1-processing-n:127.0.0.1:36693_solr
979ed142-8b09-40f8-9e53-3ea3bd0163a515301290969504939 CREATE)
[n:127.0.0.1:36693_solr ] o.a.s.h.a.CoreAdminOperation core create command
async=979ed142-8b09-40f8-9e53-3ea3bd0163a515301290969504939&qt=/admin/cores&collection.configName=conf&newCollection=true&name=foo_shard1_replica3&action=CREATE&numShards=1&collection=foo&shard=shard1&wt=javabin&version=2
[junit4] 2> 43209 INFO (qtp788185863-270) [n:127.0.0.1:53827_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={async=979ed142-8b09-40f8-9e53-3ea3bd0163a515301290970358668&qt=/admin/cores&collection.configName=conf&newCollection=true&name=foo_shard1_replica2&action=CREATE&numShards=1&collection=foo&shard=shard1&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 43209 INFO
(parallelCoreAdminExecutor-95-thread-1-processing-n:127.0.0.1:53827_solr
979ed142-8b09-40f8-9e53-3ea3bd0163a515301290970358668 CREATE)
[n:127.0.0.1:53827_solr ] o.a.s.h.a.CoreAdminOperation core create command
async=979ed142-8b09-40f8-9e53-3ea3bd0163a515301290970358668&qt=/admin/cores&collection.configName=conf&newCollection=true&name=foo_shard1_replica2&action=CREATE&numShards=1&collection=foo&shard=shard1&wt=javabin&version=2
[junit4] 2> 43212 INFO (qtp901936033-274) [n:127.0.0.1:36693_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
979ed142-8b09-40f8-9e53-3ea3bd0163a515301290969504939
[junit4] 2> 43212 INFO (qtp901936033-274) [n:127.0.0.1:36693_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=979ed142-8b09-40f8-9e53-3ea3bd0163a515301290969504939&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 43315 INFO
(zkCallback-73-thread-1-processing-n:127.0.0.1:36693_solr)
[n:127.0.0.1:36693_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/foo/state.json] for collection [foo] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 43315 INFO
(zkCallback-70-thread-1-processing-n:127.0.0.1:53827_solr)
[n:127.0.0.1:53827_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/foo/state.json] for collection [foo] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 43315 INFO
(zkCallback-72-thread-1-processing-n:127.0.0.1:49012_solr)
[n:127.0.0.1:49012_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/foo/state.json] for collection [foo] has occurred -
updating... (live nodes size: [3])
[junit4] 2> 43989 INFO (qtp215629332-271) [n:127.0.0.1:49012_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with
params
requestid=979ed142-8b09-40f8-9e53-3ea3bd0163a5&action=REQUESTSTATUS&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 43991 INFO (qtp215629332-271) [n:127.0.0.1:49012_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={requestid=979ed142-8b09-40f8-9e53-3ea3bd0163a5&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=2
[junit4] 2> 44215 INFO (qtp901936033-265) [n:127.0.0.1:36693_solr ]
o.a.s.h.a.CoreAdminOperation Checking request status for :
979ed142-8b09-40f8-9e53-3ea3bd0163a515301290969504939
[junit4] 2> 44215 INFO (qtp901936033-265) [n:127.0.0.1:36693_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={qt=/admin/cores&requestid=979ed142-8b09-40f8-9e53-3ea3bd0163a515301290969504939&action=REQUESTSTATUS&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 44240 INFO
(parallelCoreAdminExecutor-101-thread-1-processing-n:127.0.0.1:49012_solr
979ed142-8b09-40f8-9e53-3ea3bd0163a515301290970274857 CREATE)
[n:127.0.0.1:49012_solr c:foo s:shard1 x:foo_shard1_replica1]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 44242 INFO
(parallelCoreAdminExecutor-98-thread-1-processing-n:127.0.0.1:36693_solr
979ed142-8b09-40f8-9e53-3ea3bd0163a515301290969504939 CREATE)
[n:127.0.0.1:36693_solr c:foo s:shard1 x:foo_shard1_replica3]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 44243 INFO
(parallelCoreAdminExecutor-95-thread-1-processing-n:127.0.0.1:53827_solr
979ed142-8b09-40f8-9e53-3ea3bd0163a515301290970358668 CREATE)
[n:127.0.0.1:53827_solr c:foo s:shard1 x:foo_shard1_replica2]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 44258 INFO
(parallelCoreAdminExecutor-95-thread-1-processing-n:127.0.0.1:53827_solr
979ed142-8b09-40f8-9e53-3ea3bd0163a515301290970358668 CREATE)
[n:127.0.0.1:53827_solr c:foo s:shard1 x:foo_shard1_replica2]
o.a.s.s.IndexSchema [foo_shard1_replica2] Schema name=test
[junit4] 2> 44259 INFO
(parallelCoreAdminExecutor-98-thread-1-processing-n:127.0.0.1:36693_solr
979ed142-8b09-40f8-9e53-3ea3bd0163a515301290969504939 CREATE)
[n:127.0.0.1:36693_solr c:foo s:shard1 x:foo_shard1_replica3]
o.a.s.s.IndexSchema [foo_shard1_replica3] Schema name=test
[junit4] 2> 44267 INFO
(parallelCoreAdminExecutor-101-thread-1-processing-n:127.0.0.1:49012_solr
979ed142-8b09-40f8-9e53-3ea3bd0163a515301290970274857 CREATE)
[n:127.0.0.1:49012_solr c:foo s:shard1 x:foo_shard1_replica1]
o.a.s.s.IndexSchema [foo_shard1_replica1] Schema name=test
[junit4] 2> 44357 WARN
(parallelCoreAdminExecutor-101-thread-1-processing-n:127.0.0.1:49012_solr
979ed142-8b09-40f8-9e53-3ea3bd0163a515301290970274857 CREATE)
[n:127.0.0.1:49012_solr c:foo s:shard1 x:foo_shard1_replica1]
o.a.s.s.IndexSchema [foo_shard1_replica1] default search field in schema is
text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 44358 WARN
(parallelCoreAdminExecutor-101-thread-1-processing-n:127.0.0.1:49012_solr
979ed142-8b09-40f8-9e53-3ea3bd0163a515301290970274857 CREATE)
[n:127.0.0.1:49012_solr c:foo s:shard1 x:foo_shard1_replica1]
o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for
multiple copyFields (2)
[junit4] 2> 44358 WARN
(parallelCoreAdminExecutor-101-thread-1-processing-n:127.0.0.1:49012_solr
979ed142-8b09-40f8-9e53-3ea3bd0163a515301290970274857 CREATE)
[n:127.0.0.1:49012_solr c:foo s:shard1 x:foo_shard1_replica1]
o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple
copyFields (3)
[junit4] 2> 44361 INFO
(parallelCoreAdminExecutor-101-thread-1-processing-n:127.0.0.1:49012_solr
979ed142-8b09-40f8-9e53-3ea3bd0163a515301290970274857 CREATE)
[n:127.0.0.1:49012_solr c:foo s:shard1 x:foo_shard1_replica1]
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 44360 WARN
(parallelCoreAdminExecutor-98-thread-1-processing-n:127.0.0.1:36693_solr
979ed142-8b09-40f8-9e53-3ea3bd0163a515301290969504939 CREATE)
[n:127.0.0.1:36693_solr c:foo s:shard1 x:foo_shard1_replica3]
o.a.s.s.IndexSchema [foo_shard1_replica3] default search field in schema is
text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 44363 WARN
(parallelCoreAdminExecutor-98-thread-1-processing-n:127.0.0.1:36693_solr
979ed142-8b09-40f8-9e53-3ea
[...truncated too long message...]
xpired for /live_nodes]
[junit4] 2> 59130 ERROR
(OverseerCollectionConfigSetProcessor-96791758873690121-127.0.0.1:36693_solr-n_0000000002)
[n:127.0.0.1:36693_solr ] o.a.s.c.OverseerTaskProcessor
:org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode
= Session expired for /overseer/collection-queue-work
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1472)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:339)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:336)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:336)
[junit4] 2> at
org.apache.solr.cloud.DistributedQueue.fetchZkChildren(DistributedQueue.java:299)
[junit4] 2> at
org.apache.solr.cloud.OverseerTaskQueue.getTailId(OverseerTaskQueue.java:264)
[junit4] 2> at
org.apache.solr.cloud.OverseerTaskProcessor.run(OverseerTaskProcessor.java:162)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 59130 ERROR
(OverseerHdfsCoreFailoverThread-96791758873690121-127.0.0.1:36693_solr-n_0000000002)
[n:127.0.0.1:36693_solr ] o.a.s.c.OverseerAutoReplicaFailoverThread
OverseerAutoReplicaFailoverThread had an error in its thread work
loop.:org.apache.solr.common.SolrException: Could not load collection from ZK:
multicollection2
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1094)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:637)
[junit4] 2> at
org.apache.solr.common.cloud.ClusterState.getCollectionsMap(ClusterState.java:249)
[junit4] 2> at
org.apache.solr.cloud.OverseerAutoReplicaFailoverThread.doWork(OverseerAutoReplicaFailoverThread.java:165)
[junit4] 2> at
org.apache.solr.cloud.OverseerAutoReplicaFailoverThread.run(OverseerAutoReplicaFailoverThread.java:129)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by:
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode =
Session expired for /collections/multicollection2/state.json
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:356)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:353)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:353)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1106)
[junit4] 2> at
org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1092)
[junit4] 2> ... 5 more
[junit4] 2>
[junit4] 2> 59130 INFO
(OverseerCollectionConfigSetProcessor-96791758873690121-127.0.0.1:36693_solr-n_0000000002)
[n:127.0.0.1:36693_solr ] o.a.s.c.OverseerTaskProcessor According to ZK I
(id=96791758873690121-127.0.0.1:36693_solr-n_0000000002) am no longer a leader.
[junit4] 2> 59130 INFO (jetty-launcher-57-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@23fb0125{/solr,null,UNAVAILABLE}
[junit4] 2> 60606 WARN
(zkCallback-70-thread-1-processing-n:127.0.0.1:53827_solr)
[n:127.0.0.1:53827_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 60606 INFO (jetty-launcher-57-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@77d5457e{/solr,null,UNAVAILABLE}
[junit4] 2> 60610 WARN
(zkCallback-72-thread-3-processing-n:127.0.0.1:49012_solr)
[n:127.0.0.1:49012_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 60610 INFO (jetty-launcher-57-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@79e224ab{/solr,null,UNAVAILABLE}
[junit4] 2> 60613 INFO
(SUITE-CloudSolrClientTest-seed#[3E51B12B236F2E9A]-worker) [ ]
o.a.s.c.ZkTestServer connecting to 127.0.0.1:45966 45966
[junit4] 2> 60712 INFO (Thread-68) [ ] o.a.s.c.ZkTestServer connecting
to 127.0.0.1:45966 45966
[junit4] 2> 60713 WARN (Thread-68) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/aliases.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> 7 /solr/clusterprops.json
[junit4] 2> 7 /solr/clusterstate.json
[junit4] 2> 3 /solr/collections/foo/state.json
[junit4] 2> 3 /solr/collections/localShardsTestColl/state.json
[junit4] 2> 2 /solr/collections/collection1/state.json
[junit4] 2> 2 /solr/collections/multicollection2/state.json
[junit4] 2> 2
/solr/collections/foo/leader_elect/shard1/election/96791758873690120-core_node1-n_0000000000
[junit4] 2> 2 /solr/collections/multicollection1/state.json
[junit4] 2> 2
/solr/collections/localShardsTestColl/leader_elect/shard1/election/96791758873690121-core_node3-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 44 /solr/overseer/queue
[junit4] 2> 14 /solr/overseer/queue-work
[junit4] 2> 7 /solr/live_nodes
[junit4] 2> 7 /solr/collections
[junit4] 2> 7 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 60713 INFO
(SUITE-CloudSolrClientTest-seed#[3E51B12B236F2E9A]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> Oct 20, 2016 12:57:32 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 2 leaked
thread(s).
[junit4] 2> Oct 20, 2016 12:57:53 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at
org.apache.solr.client.solrj.impl.CloudSolrClientTest:
[junit4] 2> 1) Thread[id=486,
name=OverseerHdfsCoreFailoverThread-96791758873690121-127.0.0.1:36693_solr-n_0000000002,
state=TIMED_WAITING, group=Overseer Hdfs SolrCore Failover Thread.]
[junit4] 2> at java.lang.Thread.sleep(Native Method)
[junit4] 2> at
org.apache.solr.cloud.OverseerAutoReplicaFailoverThread.run(OverseerAutoReplicaFailoverThread.java:137)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Oct 20, 2016 12:57:53 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=486,
name=OverseerHdfsCoreFailoverThread-96791758873690121-127.0.0.1:36693_solr-n_0000000002,
state=TIMED_WAITING, group=Overseer Hdfs SolrCore Failover Thread.]
[junit4] 2> Oct 20, 2016 12:57:53 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: All leaked threads terminated.
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62):
{_version_=PostingsFormat(name=LuceneVarGapFixedInterval),
multiDefault=PostingsFormat(name=Direct),
a_t=PostingsFormat(name=LuceneVarGapFixedInterval),
intDefault=PostingsFormat(name=LuceneVarGapFixedInterval),
id=Lucene50(blocksize=128), text=PostingsFormat(name=MockRandom),
timestamp=PostingsFormat(name=LuceneVarGapFixedInterval)},
docValues:{multiDefault=DocValuesFormat(name=Lucene54),
intDefault=DocValuesFormat(name=Memory), id=DocValuesFormat(name=Lucene54)},
maxPointsInLeafNode=827, maxMBSortInHeap=5.133222524573624,
sim=ClassicSimilarity, locale=sr-Latn-BA, timezone=Europe/Prague
[junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation
1.8.0_102 (64-bit)/cpus=4,threads=1,free=149833376,total=303562752
[junit4] 2> NOTE: All tests run in this JVM: [JDBCStreamTest,
ExternalHttpClientTest, ShardParamsTest, SolrExampleXMLTest,
SolrExampleJettyTest, CloudSolrClientTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CloudSolrClientTest
-Dtests.seed=3E51B12B236F2E9A -Dtests.multiplier=2 -Dtests.slow=true
-Dtests.locale=sr-Latn-BA -Dtests.timezone=Europe/Prague -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J2 | CloudSolrClientTest (suite) <<<
[junit4] > Throwable #1:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE
scope at org.apache.solr.client.solrj.impl.CloudSolrClientTest:
[junit4] > 1) Thread[id=486,
name=OverseerHdfsCoreFailoverThread-96791758873690121-127.0.0.1:36693_solr-n_0000000002,
state=TIMED_WAITING, group=Overseer Hdfs SolrCore Failover Thread.]
[junit4] > at java.lang.Thread.sleep(Native Method)
[junit4] > at
org.apache.solr.cloud.OverseerAutoReplicaFailoverThread.run(OverseerAutoReplicaFailoverThread.java:137)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > at
__randomizedtesting.SeedInfo.seed([3E51B12B236F2E9A]:0)
[junit4] Completed [23/89 (1!)] on J2 in 41.47s, 10 tests, 1 error <<<
FAILURES!
[...truncated 59861 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
