lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-Tests-6.x - Build # 653 - Unstable
Date Fri, 06 Jan 2017 07:38:21 GMT
Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/653/

1 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.TestCloudPseudoReturnFields

Error Message:
1 thread leaked from SUITE scope at org.apache.solr.cloud.TestCloudPseudoReturnFields:     1) Thread[id=479, name=OverseerHdfsCoreFailoverThread-97234799762276361-127.0.0.1:59761_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:139)         at java.lang.Thread.run(Thread.java:745)

Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.TestCloudPseudoReturnFields: 
   1) Thread[id=479, name=OverseerHdfsCoreFailoverThread-97234799762276361-127.0.0.1:59761_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:139)
        at java.lang.Thread.run(Thread.java:745)
	at __randomizedtesting.SeedInfo.seed([2B2F7A5AE2C9BD7B]:0)




Build Log:
[...truncated 10819 lines...]
   [junit4] Suite: org.apache.solr.cloud.TestCloudPseudoReturnFields
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudPseudoReturnFields_2B2F7A5AE2C9BD7B-001/init-core-data-001
   [junit4]   2> 59043 INFO  (SUITE-TestCloudPseudoReturnFields-seed#[2B2F7A5AE2C9BD7B]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 59045 INFO  (SUITE-TestCloudPseudoReturnFields-seed#[2B2F7A5AE2C9BD7B]-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-core/test/J2/temp/solr.cloud.TestCloudPseudoReturnFields_2B2F7A5AE2C9BD7B-001/tempDir-001
   [junit4]   2> 59045 INFO  (SUITE-TestCloudPseudoReturnFields-seed#[2B2F7A5AE2C9BD7B]-worker) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 59045 INFO  (Thread-76) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 59045 INFO  (Thread-76) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 59145 INFO  (SUITE-TestCloudPseudoReturnFields-seed#[2B2F7A5AE2C9BD7B]-worker) [    ] o.a.s.c.ZkTestServer start zk server on port:39065
   [junit4]   2> 59168 INFO  (jetty-launcher-57-thread-2) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 59187 INFO  (jetty-launcher-57-thread-1) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 59188 INFO  (jetty-launcher-57-thread-3) [    ] o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 59191 INFO  (jetty-launcher-57-thread-1) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7d732eae{/solr,null,AVAILABLE}
   [junit4]   2> 59195 INFO  (jetty-launcher-57-thread-2) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6bfd87b4{/solr,null,AVAILABLE}
   [junit4]   2> 59197 INFO  (jetty-launcher-57-thread-2) [    ] o.e.j.s.AbstractConnector Started ServerConnector@458244ee{SSL,[ssl, http/1.1]}{127.0.0.1:55216}
   [junit4]   2> 59197 INFO  (jetty-launcher-57-thread-2) [    ] o.e.j.s.Server Started @64242ms
   [junit4]   2> 59197 INFO  (jetty-launcher-57-thread-2) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=55216}
   [junit4]   2> 59198 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> 59198 INFO  (jetty-launcher-57-thread-2) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 6.4.0
   [junit4]   2> 59198 INFO  (jetty-launcher-57-thread-2) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 59198 INFO  (jetty-launcher-57-thread-2) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 59198 INFO  (jetty-launcher-57-thread-2) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-01-06T06:48:19.259Z
   [junit4]   2> 59200 INFO  (jetty-launcher-57-thread-3) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@215915cb{/solr,null,AVAILABLE}
   [junit4]   2> 59202 INFO  (jetty-launcher-57-thread-1) [    ] o.e.j.s.AbstractConnector Started ServerConnector@abe3ded{SSL,[ssl, http/1.1]}{127.0.0.1:34690}
   [junit4]   2> 59202 INFO  (jetty-launcher-57-thread-1) [    ] o.e.j.s.Server Started @64247ms
   [junit4]   2> 59203 INFO  (jetty-launcher-57-thread-1) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34690}
   [junit4]   2> 59203 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> 59203 INFO  (jetty-launcher-57-thread-1) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 6.4.0
   [junit4]   2> 59203 INFO  (jetty-launcher-57-thread-1) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 59203 INFO  (jetty-launcher-57-thread-1) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 59203 INFO  (jetty-launcher-57-thread-1) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-01-06T06:48:19.264Z
   [junit4]   2> 59242 INFO  (jetty-launcher-57-thread-3) [    ] o.e.j.s.AbstractConnector Started ServerConnector@330cc323{SSL,[ssl, http/1.1]}{127.0.0.1:59761}
   [junit4]   2> 59242 INFO  (jetty-launcher-57-thread-3) [    ] o.e.j.s.Server Started @64287ms
   [junit4]   2> 59242 INFO  (jetty-launcher-57-thread-3) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=59761}
   [junit4]   2> 59243 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> 59243 INFO  (jetty-launcher-57-thread-3) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 6.4.0
   [junit4]   2> 59243 INFO  (jetty-launcher-57-thread-3) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 59243 INFO  (jetty-launcher-57-thread-3) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 59243 INFO  (jetty-launcher-57-thread-3) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2017-01-06T06:48:19.304Z
   [junit4]   2> 59269 INFO  (jetty-launcher-57-thread-2) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 59356 INFO  (jetty-launcher-57-thread-1) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 59398 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> 59402 INFO  (jetty-launcher-57-thread-1) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39065/solr
   [junit4]   2> 59426 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> 59434 INFO  (jetty-launcher-57-thread-3) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 59466 INFO  (jetty-launcher-57-thread-2) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39065/solr
   [junit4]   2> 59477 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> 59503 INFO  (jetty-launcher-57-thread-3) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:39065/solr
   [junit4]   2> 59542 INFO  (jetty-launcher-57-thread-2) [n:127.0.0.1:55216_solr    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55216_solr
   [junit4]   2> 59554 INFO  (jetty-launcher-57-thread-1) [n:127.0.0.1:34690_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34690_solr
   [junit4]   2> 59555 INFO  (jetty-launcher-57-thread-2) [n:127.0.0.1:55216_solr    ] o.a.s.c.Overseer Overseer (id=97234799762276358-127.0.0.1:55216_solr-n_0000000000) starting
   [junit4]   2> 59555 INFO  (zkCallback-72-thread-1-processing-n:127.0.0.1:34690_solr) [n:127.0.0.1:34690_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 59566 INFO  (zkCallback-70-thread-1-processing-n:127.0.0.1:55216_solr) [n:127.0.0.1:55216_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 59604 INFO  (jetty-launcher-57-thread-3) [n:127.0.0.1:59761_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 59607 INFO  (jetty-launcher-57-thread-3) [n:127.0.0.1:59761_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59761_solr
   [junit4]   2> 59608 INFO  (zkCallback-72-thread-1-processing-n:127.0.0.1:34690_solr) [n:127.0.0.1:34690_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 59608 INFO  (zkCallback-70-thread-1-processing-n:127.0.0.1:55216_solr) [n:127.0.0.1:55216_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 59636 INFO  (zkCallback-75-thread-1-processing-n:127.0.0.1:59761_solr) [n:127.0.0.1:59761_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 59646 INFO  (jetty-launcher-57-thread-2) [n:127.0.0.1:55216_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55216_solr
   [junit4]   2> 59647 INFO  (zkCallback-75-thread-1-processing-n:127.0.0.1:59761_solr) [n:127.0.0.1:59761_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 59647 INFO  (zkCallback-72-thread-1-processing-n:127.0.0.1:34690_solr) [n:127.0.0.1:34690_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 59648 INFO  (zkCallback-70-thread-1-processing-n:127.0.0.1:55216_solr) [n:127.0.0.1:55216_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 59648 INFO  (jetty-launcher-57-thread-1) [n:127.0.0.1:34690_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudPseudoReturnFields_2B2F7A5AE2C9BD7B-001/tempDir-001/node1/.
   [junit4]   2> 59762 INFO  (jetty-launcher-57-thread-2) [n:127.0.0.1:55216_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudPseudoReturnFields_2B2F7A5AE2C9BD7B-001/tempDir-001/node2/.
   [junit4]   2> 59800 INFO  (jetty-launcher-57-thread-3) [n:127.0.0.1:59761_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudPseudoReturnFields_2B2F7A5AE2C9BD7B-001/tempDir-001/node3/.
   [junit4]   2> 59853 INFO  (SUITE-TestCloudPseudoReturnFields-seed#[2B2F7A5AE2C9BD7B]-worker) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
   [junit4]   2> 59853 INFO  (SUITE-TestCloudPseudoReturnFields-seed#[2B2F7A5AE2C9BD7B]-worker) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:39065/solr ready
   [junit4]   2> 60447 INFO  (qtp414837585-368) [n:127.0.0.1:34690_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&property.config=solrconfig-tlog.xml&property.schema=schema-psuedo-fields.xml&collection.configName=org.apache.solr.cloud.TestCloudPseudoReturnFields_config-set&name=org.apache.solr.cloud.TestCloudPseudoReturnFields_collection&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 60453 INFO  (OverseerThreadFactory-233-thread-1-processing-n:127.0.0.1:55216_solr) [n:127.0.0.1:55216_solr    ] o.a.s.c.CreateCollectionCmd Create collection org.apache.solr.cloud.TestCloudPseudoReturnFields_collection
   [junit4]   2> 60588 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr    ] o.a.s.h.a.CoreAdminOperation core create command property.config=solrconfig-tlog.xml&property.schema=schema-psuedo-fields.xml&qt=/admin/cores&collection.configName=org.apache.solr.cloud.TestCloudPseudoReturnFields_config-set&newCollection=true&name=org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1&action=CREATE&numShards=2&collection=org.apache.solr.cloud.TestCloudPseudoReturnFields_collection&shard=shard2&wt=javabin&version=2
   [junit4]   2> 60645 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr    ] o.a.s.h.a.CoreAdminOperation core create command property.config=solrconfig-tlog.xml&property.schema=schema-psuedo-fields.xml&qt=/admin/cores&collection.configName=org.apache.solr.cloud.TestCloudPseudoReturnFields_config-set&newCollection=true&name=org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1&action=CREATE&numShards=2&collection=org.apache.solr.cloud.TestCloudPseudoReturnFields_collection&shard=shard1&wt=javabin&version=2
   [junit4]   2> 60751 INFO  (zkCallback-75-thread-1-processing-n:127.0.0.1:59761_solr) [n:127.0.0.1:59761_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection/state.json] for collection [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 60751 INFO  (zkCallback-72-thread-1-processing-n:127.0.0.1:34690_solr) [n:127.0.0.1:34690_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection/state.json] for collection [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 61616 WARN  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 61617 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
   [junit4]   2> 61643 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.s.IndexSchema [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] Schema name=test
   [junit4]   2> 61649 WARN  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.s.IndexSchema [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 61649 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.s.IndexSchema Loaded schema test/1.4 with uniqueid field id
   [junit4]   2> 61660 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.CoreContainer Creating SolrCore 'org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1' using configuration from collection org.apache.solr.cloud.TestCloudPseudoReturnFields_collection
   [junit4]   2> 61660 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.SolrCore [[org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudPseudoReturnFields_2B2F7A5AE2C9BD7B-001/tempDir-001/node1/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudPseudoReturnFields_2B2F7A5AE2C9BD7B-001/tempDir-001/node1/./org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/data/]
   [junit4]   2> 61669 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@402461a8
   [junit4]   2> 61670 WARN  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 61671 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
   [junit4]   2> 61671 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=30, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=37.6982421875, floorSegmentMB=1.3408203125, forceMergeDeletesPctAllowed=18.974917882746457, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1595436966801451
   [junit4]   2> 61686 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.s.IndexSchema [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] Schema name=test
   [junit4]   2> 61691 WARN  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.s.IndexSchema [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 61691 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.s.IndexSchema Loaded schema test/1.4 with uniqueid field id
   [junit4]   2> 61693 WARN  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 61703 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.CoreContainer Creating SolrCore 'org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1' using configuration from collection org.apache.solr.cloud.TestCloudPseudoReturnFields_collection
   [junit4]   2> 61704 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.SolrCore [[org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudPseudoReturnFields_2B2F7A5AE2C9BD7B-001/tempDir-001/node3/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudPseudoReturnFields_2B2F7A5AE2C9BD7B-001/tempDir-001/node3/./org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/data/]
   [junit4]   2> 61704 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@402461a8
   [junit4]   2> 61710 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=30, maxMergeAtOnceExplicit=50, maxMergedSegmentMB=37.6982421875, floorSegmentMB=1.3408203125, forceMergeDeletesPctAllowed=18.974917882746457, segmentsPerTier=16.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1595436966801451
   [junit4]   2> 61718 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 61718 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 61719 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 61719 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 61719 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=62.4521484375, floorSegmentMB=1.361328125, forceMergeDeletesPctAllowed=4.721124003478402, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2619421650292589
   [junit4]   2> 61720 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@4d871bb9[org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] main]
   [junit4]   2> 61720 WARN  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 61733 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/org.apache.solr.cloud.TestCloudPseudoReturnFields_config-set
   [junit4]   2> 61734 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/org.apache.solr.cloud.TestCloudPseudoReturnFields_config-set
   [junit4]   2> 61734 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 61734 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 61734 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 61735 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 61735 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 61736 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=62.4521484375, floorSegmentMB=1.361328125, forceMergeDeletesPctAllowed=4.721124003478402, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2619421650292589
   [junit4]   2> 61737 INFO  (searcherExecutor-240-thread-1-processing-n:127.0.0.1:34690_solr x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1 s:shard2 c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.SolrCore [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] Registered new searcher Searcher@4d871bb9[org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 61737 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1555756799018139648
   [junit4]   2> 61743 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 61743 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 61743 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:34690/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/
   [junit4]   2> 61743 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 61743 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.SyncStrategy https://127.0.0.1:34690/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/ has no replicas
   [junit4]   2> 61744 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1eeb7430[org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] main]
   [junit4]   2> 61746 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/org.apache.solr.cloud.TestCloudPseudoReturnFields_config-set
   [junit4]   2> 61747 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/org.apache.solr.cloud.TestCloudPseudoReturnFields_config-set
   [junit4]   2> 61747 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 61748 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:34690/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/ shard2
   [junit4]   2> 61749 INFO  (searcherExecutor-242-thread-1-processing-n:127.0.0.1:59761_solr x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1 s:shard1 c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.SolrCore [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] Registered new searcher Searcher@1eeb7430[org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 61749 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1555756799030722560
   [junit4]   2> 61757 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 61757 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 61758 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/
   [junit4]   2> 61758 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
   [junit4]   2> 61758 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.SyncStrategy https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/ has no replicas
   [junit4]   2> 61763 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/ shard1
   [junit4]   2> 61864 INFO  (zkCallback-72-thread-1-processing-n:127.0.0.1:34690_solr) [n:127.0.0.1:34690_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection/state.json] for collection [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 61865 INFO  (zkCallback-75-thread-1-processing-n:127.0.0.1:59761_solr) [n:127.0.0.1:59761_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection/state.json] for collection [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 61900 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 61903 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={property.config=solrconfig-tlog.xml&property.schema=schema-psuedo-fields.xml&qt=/admin/cores&collection.configName=org.apache.solr.cloud.TestCloudPseudoReturnFields_config-set&newCollection=true&name=org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1&action=CREATE&numShards=2&collection=org.apache.solr.cloud.TestCloudPseudoReturnFields_collection&shard=shard2&wt=javabin&version=2} status=0 QTime=1314
   [junit4]   2> 61915 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 61917 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1  x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={property.config=solrconfig-tlog.xml&property.schema=schema-psuedo-fields.xml&qt=/admin/cores&collection.configName=org.apache.solr.cloud.TestCloudPseudoReturnFields_config-set&newCollection=true&name=org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1&action=CREATE&numShards=2&collection=org.apache.solr.cloud.TestCloudPseudoReturnFields_collection&shard=shard1&wt=javabin&version=2} status=0 QTime=1272
   [junit4]   2> 61920 INFO  (qtp414837585-368) [n:127.0.0.1:34690_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> 62019 INFO  (zkCallback-75-thread-1-processing-n:127.0.0.1:59761_solr) [n:127.0.0.1:59761_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection/state.json] for collection [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 62019 INFO  (zkCallback-72-thread-1-processing-n:127.0.0.1:34690_solr) [n:127.0.0.1:34690_solr    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection/state.json] for collection [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection] has occurred - updating... (live nodes size: [3])
   [junit4]   2> 62457 INFO  (OverseerCollectionConfigSetProcessor-97234799762276358-127.0.0.1:55216_solr-n_0000000000) [n:127.0.0.1:55216_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> 62920 INFO  (qtp414837585-368) [n:127.0.0.1:34690_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&property.config=solrconfig-tlog.xml&property.schema=schema-psuedo-fields.xml&collection.configName=org.apache.solr.cloud.TestCloudPseudoReturnFields_config-set&name=org.apache.solr.cloud.TestCloudPseudoReturnFields_collection&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=2473
   [junit4]   2> 62927 INFO  (SUITE-TestCloudPseudoReturnFields-seed#[2B2F7A5AE2C9BD7B]-worker) [    ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: org.apache.solr.cloud.TestCloudPseudoReturnFields_collection failOnTimeout:true timeout (sec):330
   [junit4]   1> -
   [junit4]   1> replica:core_node2 rstate:active live:true
   [junit4]   1> replica:core_node1 rstate:active live:true
   [junit4]   1> no one is recoverying
   [junit4]   2> 62928 INFO  (SUITE-TestCloudPseudoReturnFields-seed#[2B2F7A5AE2C9BD7B]-worker) [    ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: org.apache.solr.cloud.TestCloudPseudoReturnFields_collection
   [junit4]   2> 62964 INFO  (qtp2033717574-388) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[42 (1555756800301596672)]} 0 3
   [junit4]   2> 62979 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[43 (1555756800317325312)]} 0 3
   [junit4]   2> 62997 INFO  (qtp2033717574-391) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[44 (1555756800338296832)]} 0 1
   [junit4]   2> 63005 INFO  (qtp414837585-369) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[45 (1555756800344588288)]} 0 3
   [junit4]   2> 63009 INFO  (qtp2033717574-385) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[46 (1555756800350879744)]} 0 1
   [junit4]   2> 63049 INFO  (qtp2033717574-391) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63050 INFO  (qtp2033717574-391) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7621c75d
   [junit4]   2> 63051 INFO  (qtp414837585-362) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 63051 INFO  (qtp414837585-362) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2777afe5
   [junit4]   2> 63064 INFO  (qtp414837585-362) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@22cb48e6[org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] main]
   [junit4]   2> 63065 INFO  (searcherExecutor-240-thread-1-processing-n:127.0.0.1:34690_solr x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1 s:shard2 c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection r:core_node1) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.SolrCore [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] Registered new searcher Searcher@22cb48e6[org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):c1)))}
   [junit4]   2> 63066 INFO  (qtp414837585-362) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 63066 INFO  (qtp414837585-362) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_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:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 15
   [junit4]   2> 63069 INFO  (qtp2033717574-391) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5d86c[org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] main]
   [junit4]   2> 63069 INFO  (qtp2033717574-391) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 63070 INFO  (searcherExecutor-242-thread-1-processing-n:127.0.0.1:59761_solr x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1 s:shard1 c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection r:core_node2) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.SolrCore [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] Registered new searcher Searcher@5d86c[org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):c4)))}
   [junit4]   2> 63070 INFO  (qtp2033717574-391) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_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:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 21
   [junit4]   2> 63071 INFO  (qtp2033717574-384) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/update params={_stateVer_=org.apache.solr.cloud.TestCloudPseudoReturnFields_collection:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 58
   [junit4]   2> 63112 INFO  (TEST-TestCloudPseudoReturnFields.testGlobsAndScore-seed#[2B2F7A5AE2C9BD7B]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testGlobsAndScore
   [junit4]   2> 63118 INFO  (qtp2033717574-385) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[99 (1555756800464125952)]} 0 3
   [junit4]   2> 63194 INFO  (qtp414837585-366) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1]  webapp=/solr path=/select params={q=*:*&distrib=false&fl=id&fl=score&shards.purpose=4&NOW=1483685303197&start=0&fsv=true&isShard=true&shard.url=https://127.0.0.1:34690/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/&rows=10&wt=javabin&version=2} hits=1 status=0 QTime=2
   [junit4]   2> 63194 INFO  (qtp2033717574-385) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/select params={q=*:*&distrib=false&fl=id&fl=score&shards.purpose=4&NOW=1483685303197&start=0&fsv=true&isShard=true&shard.url=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&rows=10&wt=javabin&version=2} hits=4 status=0 QTime=1
   [junit4]   2> 63204 INFO  (qtp2033717574-387) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/select params={q=*:*&distrib=false&fl=val_*,score&fl=id&shards.purpose=64&NOW=1483685303197&ids=44,46,42,43&isShard=true&shard.url=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&rows=10&wt=javabin&version=2} status=0 QTime=1
   [junit4]   2> 63210 INFO  (qtp414837585-363) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1]  webapp=/solr path=/select params={q=*:*&distrib=false&fl=val_*,score&fl=id&shards.purpose=64&NOW=1483685303197&ids=45&isShard=true&shard.url=https://127.0.0.1:34690/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/&rows=10&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 63211 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/select params={q=*:*&fl=val_*,score&rows=10&wt=javabin&version=2} hits=5 status=0 QTime=74
   [junit4]   2> 63272 INFO  (qtp414837585-363) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1]  webapp=/solr path=/select params={q=*:*&distrib=false&fl=id&fl=score&shards.purpose=4&NOW=1483685303289&start=0&fsv=true&isShard=true&shard.url=https://127.0.0.1:34690/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/&rows=10&wt=javabin&version=2} hits=1 status=0 QTime=0
   [junit4]   2> 63273 INFO  (qtp2033717574-388) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/select params={q=*:*&distrib=false&fl=id&fl=score&shards.purpose=4&NOW=1483685303289&start=0&fsv=true&isShard=true&shard.url=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&rows=10&wt=javabin&version=2} hits=4 status=0 QTime=2
   [junit4]   2> 63280 INFO  (qtp2033717574-385) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/select params={q=*:*&distrib=false&fl=val_*,subj*,score&fl=id&shards.purpose=64&NOW=1483685303289&ids=44,46,42,43&isShard=true&shard.url=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&rows=10&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 63287 INFO  (qtp414837585-367) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1]  webapp=/solr path=/select params={q=*:*&distrib=false&fl=val_*,subj*,score&fl=id&shards.purpose=64&NOW=1483685303289&ids=45&isShard=true&shard.url=https://127.0.0.1:34690/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/&rows=10&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 63289 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1]  webapp=/solr path=/select params={q=*:*&fl=val_*,subj*,score&rows=10&wt=javabin&version=2} hits=5 status=0 QTime=60
   [junit4]   2> 63295 INFO  (qtp2033717574-390) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/select params={q=*:*&distrib=false&fl=id&fl=score&shards.purpose=4&NOW=1483685303353&start=0&fsv=true&isShard=true&shard.url=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&rows=10&wt=javabin&version=2} hits=4 status=0 QTime=0
   [junit4]   2> 63299 INFO  (qtp414837585-368) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1]  webapp=/solr path=/select params={q=*:*&distrib=false&fl=id&fl=score&shards.purpose=4&NOW=1483685303353&start=0&fsv=true&isShard=true&shard.url=https://127.0.0.1:34690/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/&rows=10&wt=javabin&version=2} hits=1 status=0 QTime=0
   [junit4]   2> 63302 INFO  (qtp414837585-362) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1]  webapp=/solr path=/select params={q=*:*&distrib=false&fl=val_*&fl=subj*&fl=score&fl=id&shards.purpose=64&NOW=1483685303353&ids=45&isShard=true&shard.url=https://127.0.0.1:34690/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/&rows=10&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 63303 INFO  (qtp2033717574-391) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/select params={q=*:*&distrib=false&fl=val_*&fl=subj*&fl=score&fl=id&shards.purpose=64&NOW=1483685303353&ids=44,46,42,43&isShard=true&shard.url=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&rows=10&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 63304 INFO  (qtp2033717574-385) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/select params={q=*:*&fl=val_*&fl=subj*&fl=score&rows=10&wt=javabin&version=2} hits=5 status=0 QTime=12
   [junit4]   2> 63310 INFO  (qtp2033717574-384) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/select params={q=*:*&distrib=false&fl=id&fl=score&shards.purpose=4&NOW=1483685303368&start=0&fsv=true&isShard=true&shard.url=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&rows=10&wt=javabin&version=2} hits=4 status=0 QTime=0
   [junit4]   2> 63310 INFO  (qtp414837585-366) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1]  webapp=/solr path=/select params={q=*:*&distrib=false&fl=id&fl=score&shards.purpose=4&NOW=1483685303368&start=0&fsv=true&isShard=true&shard.url=https://127.0.0.1:34690/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/&rows=10&wt=javabin&version=2} hits=1 status=0 QTime=0
   [junit4]   2> 63313 INFO  (qtp2033717574-387) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/select params={q=*:*&distrib=false&fl=val_*&fl=subj*,score&fl=id&shards.purpose=64&NOW=1483685303368&ids=44,46,42,43&isShard=true&shard.url=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&rows=10&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 63314 INFO  (qtp414837585-363) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1]  webapp=/solr path=/select params={q=*:*&distrib=false&fl=val_*&fl=subj*,score&fl=id&shards.purpose=64&NOW=1483685303368&ids=45&isShard=true&shard.url=https://127.0.0.1:34690/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/&rows=10&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 63315 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/select params={q=*:*&fl=val_*&fl=subj*,score&rows=10&wt=javabin&version=2} hits=5 status=0 QTime=8
   [junit4]   2> 63315 INFO  (TEST-TestCloudPseudoReturnFields.testGlobsAndScore-seed#[2B2F7A5AE2C9BD7B]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testGlobsAndScore
   [junit4]   2> 63332 INFO  (TEST-TestCloudPseudoReturnFields.testMultiValued-seed#[2B2F7A5AE2C9BD7B]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testMultiValued
   [junit4]   2> 63339 INFO  (qtp2033717574-388) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[99 (1555756800692715520)]} 0 4
   [junit4]   2> 63349 INFO  (qtp2033717574-391) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/schema/fields/score params={showDefaults=true&_stateVer_=org.apache.solr.cloud.TestCloudPseudoReturnFields_collection:3&includeDynamic=true&wt=javabin&version=2} status=0 QTime=5
   [junit4]   2> 63354 INFO  (qtp2033717574-385) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/schema/fields/val_ss params={showDefaults=true&_stateVer_=org.apache.solr.cloud.TestCloudPseudoReturnFields_collection:3&includeDynamic=true&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 63433 INFO  (qtp414837585-367) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1]  webapp=/solr path=/select params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=id:42&shard.url=https://127.0.0.1:34690/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/&rows=10&version=2&q=*:*&NOW=1483685303490&isShard=true&wt=javabin} hits=0 status=0 QTime=0
   [junit4]   2> 63434 INFO  (qtp2033717574-388) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/select params={distrib=false&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&fq=id:42&shard.url=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&rows=10&version=2&q=*:*&NOW=1483685303490&isShard=true&wt=javabin} hits=1 status=0 QTime=0
   [junit4]   2> 63437 INFO  (qtp2033717574-388) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/select params={q=*:*&distrib=false&fl=id,score,val_ss,val2_ss&shards.purpose=64&NOW=1483685303490&ids=42&isShard=true&fq=id:42&shard.url=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 63438 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/select params={q=*:*&fl=id,score,val_ss,val2_ss&fq=id:42&wt=javabin&version=2} hits=1 status=0 QTime=8
   [junit4]   2> 63451 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1]  webapp=/solr path=/select params={q=id:42&distrib=false&fl=id&fl=score&shards.purpose=4&NOW=1483685303505&start=0&fsv=true&isShard=true&shard.url=https://127.0.0.1:34690/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/&rows=10&wt=javabin&version=2} hits=0 status=0 QTime=0
   [junit4]   2> 63452 INFO  (qtp2033717574-384) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/select params={q=id:42&distrib=false&fl=id&fl=score&shards.purpose=4&NOW=1483685303505&start=0&fsv=true&isShard=true&shard.url=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&rows=10&wt=javabin&version=2} hits=1 status=0 QTime=2
   [junit4]   2> 63455 INFO  (qtp2033717574-390) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/select params={q=id:42&distrib=false&fl=val_ss:val_i,+val2_ss:10&fl=id&shards.purpose=64&NOW=1483685303505&ids=42&isShard=true&shard.url=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 63461 INFO  (qtp414837585-369) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1]  webapp=/solr path=/select params={q=id:42&fl=val_ss:val_i,+val2_ss:10&wt=javabin&version=2} hits=1 status=0 QTime=17
   [junit4]   2> 63462 INFO  (TEST-TestCloudPseudoReturnFields.testMultiValued-seed#[2B2F7A5AE2C9BD7B]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testMultiValued
   [junit4]   2> 63477 INFO  (TEST-TestCloudPseudoReturnFields.testFunctionsAndScoreRTG-seed#[2B2F7A5AE2C9BD7B]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testFunctionsAndScoreRTG
   [junit4]   2> 63481 INFO  (qtp2033717574-390) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[99 (1555756800845807616)]} 0 1
   [junit4]   2> 63488 INFO  (qtp2033717574-391) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard2=https://127.0.0.1:34690/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/
   [junit4]   2> 63488 INFO  (qtp2033717574-391) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/
   [junit4]   2> 63505 INFO  (qtp2033717574-385) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/get params={distrib=false&qt=/get&omitHeader=true&fl=score&fl=log(val_i)&fl=abs(val_i)&shards.purpose=1&NOW=1483685303544&ids=42&isShard=true&shard.url=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=14
   [junit4]   2> 63506 INFO  (qtp2033717574-391) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/get params={qt=/get&fl=score&fl=log(val_i)&fl=abs(val_i)&ids=42&wt=javabin&version=2} status=0 QTime=22
   [junit4]   2> 63509 INFO  (qtp2033717574-388) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard2=https://127.0.0.1:34690/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/
   [junit4]   2> 63509 INFO  (qtp2033717574-388) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/
   [junit4]   2> 63511 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/get params={distrib=false&qt=/get&omitHeader=true&fl=score&fl=log(val_i),abs(val_i)&shards.purpose=1&NOW=1483685303569&ids=42&isShard=true&shard.url=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0
   [junit4]   2> 63512 INFO  (qtp2033717574-388) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/get params={qt=/get&fl=score&fl=log(val_i),abs(val_i)&ids=42&wt=javabin&version=2} status=0 QTime=3
   [junit4]   2> 63521 INFO  (qtp414837585-367) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard2=https://127.0.0.1:34690/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/
   [junit4]   2> 63521 INFO  (qtp414837585-367) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/
   [junit4]   2> 63524 INFO  (qtp2033717574-384) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/get params={distrib=false&qt=/get&omitHeader=true&fl=score,log(val_i)&fl=abs(val_i)&shards.purpose=1&NOW=1483685303582&ids=42&isShard=true&shard.url=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0
   [junit4]   2> 63524 INFO  (qtp414837585-367) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1]  webapp=/solr path=/get params={qt=/get&fl=score,log(val_i)&fl=abs(val_i)&ids=42&wt=javabin&version=2} status=0 QTime=3
   [junit4]   2> 63527 INFO  (qtp414837585-362) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard2=https://127.0.0.1:34690/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/
   [junit4]   2> 63528 INFO  (qtp414837585-362) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/
   [junit4]   2> 63530 INFO  (qtp2033717574-390) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/get params={distrib=false&qt=/get&omitHeader=true&fl=score,log(val_i),abs(val_i)&shards.purpose=1&NOW=1483685303588&ids=42&isShard=true&shard.url=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0
   [junit4]   2> 63531 INFO  (qtp414837585-362) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1]  webapp=/solr path=/get params={qt=/get&fl=score,log(val_i),abs(val_i)&ids=42&wt=javabin&version=2} status=0 QTime=3
   [junit4]   2> 63533 INFO  (qtp414837585-369) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard2=https://127.0.0.1:34690/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/
   [junit4]   2> 63533 INFO  (qtp414837585-369) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/
   [junit4]   2> 63548 INFO  (qtp2033717574-385) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@20a9a6c3[org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] realtime]
   [junit4]   2> 63549 INFO  (qtp2033717574-385) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/get params={distrib=false&qt=/get&omitHeader=true&fl=score&fl=log(val_i)&fl=abs(val_i)&shards.purpose=1&NOW=1483685303594&ids=99&isShard=true&shard.url=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=13
   [junit4]   2> 63549 INFO  (qtp414837585-369) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1]  webapp=/solr path=/get params={qt=/get&fl=score&fl=log(val_i)&fl=abs(val_i)&ids=99&wt=javabin&version=2} status=0 QTime=16
   [junit4]   2> 63552 INFO  (qtp2033717574-391) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard2=https://127.0.0.1:34690/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/
   [junit4]   2> 63552 INFO  (qtp2033717574-391) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/
   [junit4]   2> 63555 INFO  (qtp2033717574-387) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/get params={distrib=false&qt=/get&omitHeader=true&fl=score&fl=log(val_i),abs(val_i)&shards.purpose=1&NOW=1483685303613&ids=99&isShard=true&shard.url=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0
   [junit4]   2> 63556 INFO  (qtp2033717574-391) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/get params={qt=/get&fl=score&fl=log(val_i),abs(val_i)&ids=99&wt=javabin&version=2} status=0 QTime=3
   [junit4]   2> 63558 INFO  (qtp414837585-368) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard2=https://127.0.0.1:34690/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/
   [junit4]   2> 63558 INFO  (qtp414837585-368) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/
   [junit4]   2> 63561 INFO  (qtp2033717574-389) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/get params={distrib=false&qt=/get&_stateVer_=org.apache.solr.cloud.TestCloudPseudoReturnFields_collection:3&fl=score,log(val_i)&fl=abs(val_i)&shards.purpose=1&shard.url=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&version=2&shards.qt=/get&omitHeader=true&NOW=1483685303619&ids=99&isShard=true&wt=javabin} status=0 QTime=0
   [junit4]   2> 63562 INFO  (qtp414837585-368) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1]  webapp=/solr path=/get params={qt=/get&_stateVer_=org.apache.solr.cloud.TestCloudPseudoReturnFields_collection:3&fl=score,log(val_i)&fl=abs(val_i)&ids=99&wt=javabin&version=2} status=0 QTime=3
   [junit4]   2> 63568 INFO  (qtp2033717574-388) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard2=https://127.0.0.1:34690/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/
   [junit4]   2> 63568 INFO  (qtp2033717574-388) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/
   [junit4]   2> 63570 INFO  (qtp2033717574-390) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/get params={distrib=false&qt=/get&omitHeader=true&fl=score,log(val_i),abs(val_i)&shards.purpose=1&NOW=1483685303629&ids=99&isShard=true&shard.url=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&wt=javabin&version=2&shards.qt=/get} status=0 QTime=0
   [junit4]   2> 63571 INFO  (qtp2033717574-388) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/get params={qt=/get&fl=score,log(val_i),abs(val_i)&ids=99&wt=javabin&version=2} status=0 QTime=3
   [junit4]   2> 63572 INFO  (TEST-TestCloudPseudoReturnFields.testFunctionsAndScoreRTG-seed#[2B2F7A5AE2C9BD7B]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testFunctionsAndScoreRTG
   [junit4]   2> 63588 INFO  (TEST-TestCloudPseudoReturnFields.testFilterAndOneRealFieldRTG-seed#[2B2F7A5AE2C9BD7B]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testFilterAndOneRealFieldRTG
   [junit4]   2> 63592 INFO  (qtp2033717574-384) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[99 (1555756800962199552)]} 0 1
   [junit4]   2> 63595 INFO  (qtp2033717574-387) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard2=https://127.0.0.1:34690/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1/
   [junit4]   2> 63595 INFO  (qtp2033717574-387) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.h.c.RealTimeGetComponent LOOKUP_SLICE:shard1=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/
   [junit4]   2> 63601 INFO  (qtp2033717574-385) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@776f5e54[org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] realtime]
   [junit4]   2> 63601 INFO  (qtp2033717574-385) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/get params={my_var=uncommitted&distrib=false&qt=/get&fl=id,val_i&shards.purpose=1&fq={!field+f%3D'subject'+v%3D$my_var}&shard.url=https://127.0.0.1:59761/solr/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1/&version=2&shards.qt=/get&omitHeader=true&NOW=1483685303655&ids=42,99&isShard=true&wt=javabin} status=0 QTime=4
   [junit4]   2> 63602 INFO  (qtp2033717574-387) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.c.S.Request [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/get params={my_var=uncommitted&qt=/get&fl=id,val_i&ids=42&ids=99&fq={!field+f%3D'subject'+v%3D$my_var}&wt=javabin&version=2} status=0 QTime=7
   [junit4]   2> 63602 INFO  (TEST-TestCloudPseudoReturnFields.testFilterAndOneRealFieldRTG-seed#[2B2F7A5AE2C9BD7B]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testFilterAndOneRealFieldRTG
   [junit4]   2> 63616 INFO  (TEST-TestCloudPseudoReturnFields.testAllRealFields-seed#[2B2F7A5AE2C9BD7B]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testAllRealFields
   [junit4]   2> 63620 INFO  (qtp2033717574-391) [n:127.0.0.1:59761_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[99 (1555756800991559680)]} 0 1
   [junit4]   2> 63629 INFO  (qtp414837585-365) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.c.S.Request [org.apache.solr.clou

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

oudPseudoReturnFields_collection s:shard1 r:core_node2 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard1_replica1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.org.apache.solr.cloud.TestCloudPseudoReturnFields_collection.shard1.replica1
   [junit4]   2> 67863 INFO  (coreCloseExecutor-252-thread-1) [n:127.0.0.1:34690_solr c:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection s:shard2 r:core_node1 x:org.apache.solr.cloud.TestCloudPseudoReturnFields_collection_shard2_replica1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.org.apache.solr.cloud.TestCloudPseudoReturnFields_collection.shard2.replica1
   [junit4]   2> 67874 INFO  (jetty-launcher-57-thread-3) [    ] o.a.s.c.Overseer Overseer (id=97234799762276360-127.0.0.1:34690_solr-n_0000000001) closing
   [junit4]   2> 67875 INFO  (OverseerStateUpdate-97234799762276360-127.0.0.1:34690_solr-n_0000000001) [n:127.0.0.1:34690_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:34690_solr
   [junit4]   2> 67877 INFO  (zkCallback-75-thread-1-processing-n:127.0.0.1:59761_solr) [n:127.0.0.1:59761_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
   [junit4]   2> 67879 INFO  (zkCallback-75-thread-2-processing-n:127.0.0.1:59761_solr) [n:127.0.0.1:59761_solr    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:59761_solr
   [junit4]   2> 67879 INFO  (zkCallback-75-thread-2-processing-n:127.0.0.1:59761_solr) [n:127.0.0.1:59761_solr    ] o.a.s.c.Overseer Overseer (id=97234799762276361-127.0.0.1:59761_solr-n_0000000002) starting
   [junit4]   2> 69316 WARN  (zkCallback-70-thread-5-processing-n:127.0.0.1:55216_solr) [n:127.0.0.1:55216_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 69317 INFO  (jetty-launcher-57-thread-2) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 69317 INFO  (jetty-launcher-57-thread-2) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6bfd87b4{/solr,null,UNAVAILABLE}
   [junit4]   2> 69377 WARN  (zkCallback-72-thread-2-processing-n:127.0.0.1:34690_solr) [n:127.0.0.1:34690_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 69377 INFO  (jetty-launcher-57-thread-3) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 69378 INFO  (jetty-launcher-57-thread-3) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7d732eae{/solr,null,UNAVAILABLE}
   [junit4]   2> 69404 WARN  (OverseerStateUpdate-97234799762276361-127.0.0.1:59761_solr-n_0000000002) [n:127.0.0.1:59761_solr    ] o.a.s.c.Overseer Solr cannot talk to ZK, exiting Overseer work queue loop
   [junit4]   2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer/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.DistributedQueue.firstChild(DistributedQueue.java:279)
   [junit4]   2> 	at org.apache.solr.cloud.DistributedQueue.firstElement(DistributedQueue.java:384)
   [junit4]   2> 	at org.apache.solr.cloud.DistributedQueue.peek(DistributedQueue.java:124)
   [junit4]   2> 	at org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:148)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 69404 INFO  (OverseerStateUpdate-97234799762276361-127.0.0.1:59761_solr-n_0000000002) [n:127.0.0.1:59761_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:59761_solr
   [junit4]   2> 69404 WARN  (zkCallback-75-thread-2-processing-n:127.0.0.1:59761_solr) [n:127.0.0.1:59761_solr    ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
   [junit4]   2> 69404 INFO  (jetty-launcher-57-thread-1) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 69406 INFO  (jetty-launcher-57-thread-1) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@215915cb{/solr,null,UNAVAILABLE}
   [junit4]   2> 69412 INFO  (SUITE-TestCloudPseudoReturnFields-seed#[2B2F7A5AE2C9BD7B]-worker) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:39065 39065
   [junit4]   2> 69514 INFO  (Thread-76) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:39065 39065
   [junit4]   2> 69515 WARN  (Thread-76) [    ] o.a.s.c.ZkTestServer Watch limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2> 	4	/solr/aliases.json
   [junit4]   2> 	3	/solr/security.json
   [junit4]   2> 	2	/solr/configs/org.apache.solr.cloud.TestCloudPseudoReturnFields_config-set
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2> 	4	/solr/clusterstate.json
   [junit4]   2> 	4	/solr/clusterprops.json
   [junit4]   2> 	2	/solr/collections/org.apache.solr.cloud.TestCloudPseudoReturnFields_collection/state.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2> 	12	/solr/overseer/queue
   [junit4]   2> 	8	/solr/overseer/collection-queue-work
   [junit4]   2> 	4	/solr/live_nodes
   [junit4]   2> 	4	/solr/collections
   [junit4]   2> 	3	/solr/overseer/queue-work
   [junit4]   2> 
   [junit4]   2> 69515 INFO  (SUITE-TestCloudPseudoReturnFields-seed#[2B2F7A5AE2C9BD7B]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> Jan 06, 2017 6:48:29 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 3 leaked thread(s).
   [junit4]   2> 69903 WARN  (OverseerCollectionConfigSetProcessor-97234799762276361-127.0.0.1:59761_solr-n_0000000002) [n:127.0.0.1:59761_solr    ] o.a.s.c.OverseerTaskProcessor Overseer cannot talk to ZK
   [junit4]   2> Jan 06, 2017 6:48:49 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> SEVERE: 1 thread leaked from SUITE scope at org.apache.solr.cloud.TestCloudPseudoReturnFields: 
   [junit4]   2>    1) Thread[id=479, name=OverseerHdfsCoreFailoverThread-97234799762276361-127.0.0.1:59761_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:139)
   [junit4]   2>         at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> Jan 06, 2017 6:48:49 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
   [junit4]   2> INFO: Starting to interrupt leaked threads:
   [junit4]   2>    1) Thread[id=479, name=OverseerHdfsCoreFailoverThread-97234799762276361-127.0.0.1:59761_solr-n_0000000002, state=TIMED_WAITING, group=Overseer Hdfs SolrCore Failover Thread.]
   [junit4]   2> Jan 06, 2017 6:48:49 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
   [junit4]   2> INFO: All leaked threads terminated.
   [junit4]   2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=8, maxDocsPerChunk=846, blockSize=462), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=8, blockSize=462)), sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=ar-MA, timezone=Europe/Athens
   [junit4]   2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=4,threads=1,free=111070912,total=404226048
   [junit4]   2> NOTE: All tests run in this JVM: [BJQParserTest, BitVectorTest, TestMacros, BasicAuthIntegrationTest, CoreAdminHandlerTest, TestConfigSets, TestSubQueryTransformerCrossCore, CircularListTest, OverseerTaskQueueTest, CoreSorterTest, BlockDirectoryTest, TestCloudPseudoReturnFields]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestCloudPseudoReturnFields -Dtests.seed=2B2F7A5AE2C9BD7B -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=ar-MA -Dtests.timezone=Europe/Athens -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.00s J2 | TestCloudPseudoReturnFields (suite) <<<
   [junit4]    > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.cloud.TestCloudPseudoReturnFields: 
   [junit4]    >    1) Thread[id=479, name=OverseerHdfsCoreFailoverThread-97234799762276361-127.0.0.1:59761_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:139)
   [junit4]    >         at java.lang.Thread.run(Thread.java:745)
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([2B2F7A5AE2C9BD7B]:0)
   [junit4] Completed [39/677 (1!)] on J2 in 30.59s, 30 tests, 1 error <<< FAILURES!

[...truncated 64541 lines...]



Mime
View raw message