You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/05/06 17:24:51 UTC
[JENKINS-Experimental-GC] Lucene-Solr-master-MacOSX (64bit/jdk-14)
- Build # 5983 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/5983/
Java: 64bit/jdk-14 -XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseShenandoahGC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.search.facet.RangeFacetCloudTest
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.search.facet.RangeFacetCloudTest: 1) Thread[id=11838, name=SessionTracker, state=TIMED_WAITING, group=TGRP-RangeFacetCloudTest] at java.base@14/java.lang.Thread.sleep(Native Method) at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.search.facet.RangeFacetCloudTest:
1) Thread[id=11838, name=SessionTracker, state=TIMED_WAITING, group=TGRP-RangeFacetCloudTest]
at java.base@14/java.lang.Thread.sleep(Native Method)
at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
at __randomizedtesting.SeedInfo.seed([6856440F9C67FFC2]:0)
Build Log:
[...truncated 13989 lines...]
[junit4] Suite: org.apache.solr.search.facet.RangeFacetCloudTest
[junit4] 2> 1233723 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1233724 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J2/temp/solr.search.facet.RangeFacetCloudTest_6856440F9C67FFC2-001/data-dir-58-001
[junit4] 2> 1233725 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1233726 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 1233726 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1233726 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J2/temp/solr.search.facet.RangeFacetCloudTest_6856440F9C67FFC2-001/tempDir-001
[junit4] 2> 1233727 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1233727 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1233727 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1233834 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 60617
[junit4] 2> 1233834 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:60617
[junit4] 2> 1233834 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:60617
[junit4] 2> 1233834 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 60617
[junit4] 2> 1233836 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1233840 INFO (zkConnectionManagerCallback-7284-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1233840 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1233843 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1233846 INFO (zkConnectionManagerCallback-7286-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1233846 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1233847 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1233850 INFO (zkConnectionManagerCallback-7288-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1233850 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1233960 INFO (jetty-launcher-7289-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1233960 INFO (jetty-launcher-7289-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1233960 INFO (jetty-launcher-7289-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1233960 INFO (jetty-launcher-7289-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1233960 INFO (jetty-launcher-7289-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1233960 INFO (jetty-launcher-7289-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1233960 INFO (jetty-launcher-7289-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1233960 INFO (jetty-launcher-7289-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1233960 INFO (jetty-launcher-7289-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14+36
[junit4] 2> 1233960 INFO (jetty-launcher-7289-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14+36
[junit4] 2> 1233960 INFO (jetty-launcher-7289-thread-3) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14+36
[junit4] 2> 1233960 INFO (jetty-launcher-7289-thread-4) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14+36
[junit4] 2> 1233966 INFO (jetty-launcher-7289-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1233966 INFO (jetty-launcher-7289-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1233967 INFO (jetty-launcher-7289-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1233967 INFO (jetty-launcher-7289-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1233967 INFO (jetty-launcher-7289-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1233967 INFO (jetty-launcher-7289-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1233967 INFO (jetty-launcher-7289-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1233967 INFO (jetty-launcher-7289-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1233967 INFO (jetty-launcher-7289-thread-4) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1233968 INFO (jetty-launcher-7289-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2dc79ea5{/solr,null,AVAILABLE}
[junit4] 2> 1233968 INFO (jetty-launcher-7289-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7626cc5e{/solr,null,AVAILABLE}
[junit4] 2> 1233968 INFO (jetty-launcher-7289-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@38b5a5b2{/solr,null,AVAILABLE}
[junit4] 2> 1233968 INFO (jetty-launcher-7289-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1233968 INFO (jetty-launcher-7289-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1233968 INFO (jetty-launcher-7289-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 1233968 INFO (jetty-launcher-7289-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1413440e{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:60627}
[junit4] 2> 1233968 INFO (jetty-launcher-7289-thread-2) [ ] o.e.j.s.Server Started @1234048ms
[junit4] 2> 1233968 INFO (jetty-launcher-7289-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60627}
[junit4] 2> 1233969 INFO (jetty-launcher-7289-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2e73b238{/solr,null,AVAILABLE}
[junit4] 2> 1233969 INFO (jetty-launcher-7289-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@a521e61{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:60628}
[junit4] 2> 1233969 ERROR (jetty-launcher-7289-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1233969 INFO (jetty-launcher-7289-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1233969 INFO (jetty-launcher-7289-thread-4) [ ] o.e.j.s.Server Started @1234049ms
[junit4] 2> 1233969 INFO (jetty-launcher-7289-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60628}
[junit4] 2> 1233969 INFO (jetty-launcher-7289-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 1233969 INFO (jetty-launcher-7289-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1233969 INFO (jetty-launcher-7289-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr
[junit4] 2> 1233969 INFO (jetty-launcher-7289-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-06T16:44:58.528634Z
[junit4] 2> 1233969 ERROR (jetty-launcher-7289-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1233969 INFO (jetty-launcher-7289-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1233969 INFO (jetty-launcher-7289-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 1233969 INFO (jetty-launcher-7289-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1233969 INFO (jetty-launcher-7289-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr
[junit4] 2> 1233969 INFO (jetty-launcher-7289-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-06T16:44:58.528782Z
[junit4] 2> 1233970 INFO (jetty-launcher-7289-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@57224966{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:60629}
[junit4] 2> 1233970 INFO (jetty-launcher-7289-thread-1) [ ] o.e.j.s.Server Started @1234050ms
[junit4] 2> 1233970 INFO (jetty-launcher-7289-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60629}
[junit4] 2> 1233970 INFO (jetty-launcher-7289-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@44b704c0{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:60630}
[junit4] 2> 1233970 INFO (jetty-launcher-7289-thread-3) [ ] o.e.j.s.Server Started @1234050ms
[junit4] 2> 1233970 ERROR (jetty-launcher-7289-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1233970 INFO (jetty-launcher-7289-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1233970 INFO (jetty-launcher-7289-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 1233970 INFO (jetty-launcher-7289-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=60630}
[junit4] 2> 1233970 INFO (jetty-launcher-7289-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1233970 INFO (jetty-launcher-7289-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr
[junit4] 2> 1233970 INFO (jetty-launcher-7289-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-06T16:44:58.529411Z
[junit4] 2> 1233970 ERROR (jetty-launcher-7289-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1233970 INFO (jetty-launcher-7289-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1233970 INFO (jetty-launcher-7289-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 1233970 INFO (jetty-launcher-7289-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1233970 INFO (jetty-launcher-7289-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr
[junit4] 2> 1233970 INFO (jetty-launcher-7289-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-06T16:44:58.529731Z
[junit4] 2> 1233971 INFO (jetty-launcher-7289-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1233971 INFO (jetty-launcher-7289-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1233972 INFO (jetty-launcher-7289-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1233973 INFO (jetty-launcher-7289-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1233976 INFO (zkConnectionManagerCallback-7295-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1233976 INFO (jetty-launcher-7289-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1233977 INFO (zkConnectionManagerCallback-7291-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1233977 INFO (jetty-launcher-7289-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1233977 INFO (jetty-launcher-7289-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1233978 INFO (zkConnectionManagerCallback-7297-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1233979 INFO (jetty-launcher-7289-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1233979 INFO (jetty-launcher-7289-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1233979 INFO (zkConnectionManagerCallback-7293-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1233979 INFO (jetty-launcher-7289-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1233980 INFO (jetty-launcher-7289-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1233981 INFO (jetty-launcher-7289-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1234087 INFO (jetty-launcher-7289-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1234088 WARN (jetty-launcher-7289-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1d0f632b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1234088 WARN (jetty-launcher-7289-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1d0f632b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1234091 INFO (jetty-launcher-7289-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1234092 WARN (jetty-launcher-7289-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@efe85e0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1234092 WARN (jetty-launcher-7289-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@20689c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1234092 WARN (jetty-launcher-7289-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@20689c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1234092 WARN (jetty-launcher-7289-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@efe85e0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1234093 INFO (jetty-launcher-7289-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60617/solr
[junit4] 2> 1234094 INFO (jetty-launcher-7289-thread-1) [n:127.0.0.1:60629_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1234097 WARN (jetty-launcher-7289-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@587b7cb8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1234097 WARN (jetty-launcher-7289-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@587b7cb8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1234098 INFO (jetty-launcher-7289-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60617/solr
[junit4] 2> 1234099 INFO (zkConnectionManagerCallback-7315-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1234099 INFO (jetty-launcher-7289-thread-1) [n:127.0.0.1:60629_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1234099 INFO (jetty-launcher-7289-thread-2) [n:127.0.0.1:60627_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1234103 INFO (zkConnectionManagerCallback-7319-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1234103 INFO (jetty-launcher-7289-thread-2) [n:127.0.0.1:60627_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1234194 INFO (jetty-launcher-7289-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1234195 WARN (jetty-launcher-7289-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3c7297f0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1234195 WARN (jetty-launcher-7289-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3c7297f0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1234198 WARN (jetty-launcher-7289-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1c8efd4e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1234198 WARN (jetty-launcher-7289-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1c8efd4e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1234199 INFO (jetty-launcher-7289-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60617/solr
[junit4] 2> 1234200 INFO (jetty-launcher-7289-thread-4) [n:127.0.0.1:60628_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1234204 INFO (zkConnectionManagerCallback-7334-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1234204 INFO (jetty-launcher-7289-thread-4) [n:127.0.0.1:60628_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1234237 INFO (jetty-launcher-7289-thread-4) [n:127.0.0.1:60628_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:60628_solr
[junit4] 2> 1234239 INFO (jetty-launcher-7289-thread-4) [n:127.0.0.1:60628_solr ] o.a.s.c.Overseer Overseer (id=72171581018865673-127.0.0.1:60628_solr-n_0000000000) starting
[junit4] 2> 1234256 INFO (OverseerStateUpdate-72171581018865673-127.0.0.1:60628_solr-n_0000000000) [n:127.0.0.1:60628_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:60628_solr
[junit4] 2> 1234257 INFO (jetty-launcher-7289-thread-4) [n:127.0.0.1:60628_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60628_solr
[junit4] 2> 1234261 INFO (OverseerStateUpdate-72171581018865673-127.0.0.1:60628_solr-n_0000000000) [n:127.0.0.1:60628_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1234276 INFO (jetty-launcher-7289-thread-4) [n:127.0.0.1:60628_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1234276 WARN (jetty-launcher-7289-thread-4) [n:127.0.0.1:60628_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1234295 INFO (jetty-launcher-7289-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1234297 WARN (jetty-launcher-7289-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@75f1c3a0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1234297 WARN (jetty-launcher-7289-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@75f1c3a0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1234309 WARN (jetty-launcher-7289-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@64c46fee[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1234309 WARN (jetty-launcher-7289-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@64c46fee[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1234309 INFO (jetty-launcher-7289-thread-4) [n:127.0.0.1:60628_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1234310 INFO (jetty-launcher-7289-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:60617/solr
[junit4] 2> 1234313 INFO (jetty-launcher-7289-thread-3) [n:127.0.0.1:60630_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1234327 INFO (zkConnectionManagerCallback-7356-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1234328 INFO (jetty-launcher-7289-thread-3) [n:127.0.0.1:60630_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1234337 INFO (jetty-launcher-7289-thread-4) [n:127.0.0.1:60628_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60628.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e3224bb
[junit4] 2> 1234374 INFO (jetty-launcher-7289-thread-3) [n:127.0.0.1:60630_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1234409 INFO (jetty-launcher-7289-thread-3) [n:127.0.0.1:60630_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:60630_solr as DOWN
[junit4] 2> 1234418 INFO (jetty-launcher-7289-thread-3) [n:127.0.0.1:60630_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1234418 INFO (jetty-launcher-7289-thread-3) [n:127.0.0.1:60630_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60630_solr
[junit4] 2> 1234429 INFO (zkCallback-7355-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1234429 INFO (zkCallback-7333-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1234445 INFO (jetty-launcher-7289-thread-3) [n:127.0.0.1:60630_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1234445 WARN (jetty-launcher-7289-thread-3) [n:127.0.0.1:60630_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1234454 INFO (jetty-launcher-7289-thread-4) [n:127.0.0.1:60628_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60628.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e3224bb
[junit4] 2> 1234454 INFO (jetty-launcher-7289-thread-4) [n:127.0.0.1:60628_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60628.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e3224bb
[junit4] 2> 1234455 INFO (jetty-launcher-7289-thread-4) [n:127.0.0.1:60628_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J2/temp/solr.search.facet.RangeFacetCloudTest_6856440F9C67FFC2-001/tempDir-001/node4/.
[junit4] 2> 1234460 INFO (jetty-launcher-7289-thread-2) [n:127.0.0.1:60627_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1234469 INFO (jetty-launcher-7289-thread-3) [n:127.0.0.1:60630_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1234481 INFO (jetty-launcher-7289-thread-2) [n:127.0.0.1:60627_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:60627_solr as DOWN
[junit4] 2> 1234488 INFO (jetty-launcher-7289-thread-2) [n:127.0.0.1:60627_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1234488 INFO (jetty-launcher-7289-thread-2) [n:127.0.0.1:60627_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60627_solr
[junit4] 2> 1234494 INFO (zkCallback-7333-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1234494 INFO (zkCallback-7318-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1234495 INFO (zkCallback-7355-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 1234500 INFO (jetty-launcher-7289-thread-2) [n:127.0.0.1:60627_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1234501 WARN (jetty-launcher-7289-thread-2) [n:127.0.0.1:60627_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1234508 INFO (jetty-launcher-7289-thread-3) [n:127.0.0.1:60630_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60630.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e3224bb
[junit4] 2> 1234528 INFO (jetty-launcher-7289-thread-3) [n:127.0.0.1:60630_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60630.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e3224bb
[junit4] 2> 1234528 INFO (jetty-launcher-7289-thread-3) [n:127.0.0.1:60630_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60630.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e3224bb
[junit4] 2> 1234529 INFO (jetty-launcher-7289-thread-2) [n:127.0.0.1:60627_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1234530 INFO (jetty-launcher-7289-thread-3) [n:127.0.0.1:60630_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J2/temp/solr.search.facet.RangeFacetCloudTest_6856440F9C67FFC2-001/tempDir-001/node3/.
[junit4] 2> 1234568 INFO (jetty-launcher-7289-thread-2) [n:127.0.0.1:60627_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60627.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e3224bb
[junit4] 2> 1234583 INFO (jetty-launcher-7289-thread-2) [n:127.0.0.1:60627_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60627.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e3224bb
[junit4] 2> 1234583 INFO (jetty-launcher-7289-thread-2) [n:127.0.0.1:60627_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60627.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e3224bb
[junit4] 2> 1234584 INFO (jetty-launcher-7289-thread-2) [n:127.0.0.1:60627_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J2/temp/solr.search.facet.RangeFacetCloudTest_6856440F9C67FFC2-001/tempDir-001/node2/.
[junit4] 2> 1234739 INFO (jetty-launcher-7289-thread-1) [n:127.0.0.1:60629_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 1234816 INFO (jetty-launcher-7289-thread-1) [n:127.0.0.1:60629_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:60629_solr as DOWN
[junit4] 2> 1234832 INFO (jetty-launcher-7289-thread-1) [n:127.0.0.1:60629_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1234832 INFO (jetty-launcher-7289-thread-1) [n:127.0.0.1:60629_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60629_solr
[junit4] 2> 1234840 INFO (zkCallback-7333-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1234842 INFO (zkCallback-7355-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1234842 INFO (zkCallback-7318-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1234843 INFO (zkCallback-7314-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 1234866 INFO (jetty-launcher-7289-thread-1) [n:127.0.0.1:60629_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1234866 WARN (jetty-launcher-7289-thread-1) [n:127.0.0.1:60629_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 1234902 INFO (jetty-launcher-7289-thread-1) [n:127.0.0.1:60629_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1234944 INFO (jetty-launcher-7289-thread-1) [n:127.0.0.1:60629_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60629.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e3224bb
[junit4] 2> 1234962 INFO (jetty-launcher-7289-thread-1) [n:127.0.0.1:60629_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60629.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e3224bb
[junit4] 2> 1234962 INFO (jetty-launcher-7289-thread-1) [n:127.0.0.1:60629_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60629.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e3224bb
[junit4] 2> 1234965 INFO (jetty-launcher-7289-thread-1) [n:127.0.0.1:60629_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J2/temp/solr.search.facet.RangeFacetCloudTest_6856440F9C67FFC2-001/tempDir-001/node1/.
[junit4] 2> 1234989 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=4
[junit4] 2> 1234990 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1235006 INFO (zkConnectionManagerCallback-7382-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1235006 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1235016 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 1235026 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:60617/solr ready
[junit4] 2> 1241505 INFO (qtp450148644-11885) [n:127.0.0.1:60627_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params property.config=solrconfig-minimal.xml&collection.configName=org.apache.solr.search.facet.RangeFacetCloudTest_configSet&maxShardsPerNode=1&name=org.apache.solr.search.facet.RangeFacetCloudTest&nrtReplicas=1&action=CREATE&numShards=4&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1241553 INFO (OverseerThreadFactory-7341-thread-1-processing-n:127.0.0.1:60628_solr) [n:127.0.0.1:60628_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection org.apache.solr.search.facet.RangeFacetCloudTest
[junit4] 2> 1241762 INFO (qtp450148644-11893) [n:127.0.0.1:60627_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1241765 INFO (qtp76931203-11886) [n:127.0.0.1:60628_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1241769 INFO (qtp1508228138-11890) [n:127.0.0.1:60629_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1241773 INFO (qtp1248916478-11892) [n:127.0.0.1:60630_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=1
[junit4] 2> 1241781 INFO (qtp450148644-11881) [n:127.0.0.1:60627_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1241782 INFO (qtp76931203-11887) [n:127.0.0.1:60628_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1241784 INFO (qtp1508228138-11894) [n:127.0.0.1:60629_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1241786 INFO (qtp1248916478-11899) [n:127.0.0.1:60630_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0
[junit4] 2> 1241882 INFO (OverseerStateUpdate-72171581018865673-127.0.0.1:60628_solr-n_0000000000) [n:127.0.0.1:60628_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"org.apache.solr.search.facet.RangeFacetCloudTest",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:60630/solr",
[junit4] 2> "node_name":"127.0.0.1:60630_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1241989 INFO (OverseerStateUpdate-72171581018865673-127.0.0.1:60628_solr-n_0000000000) [n:127.0.0.1:60628_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"org.apache.solr.search.facet.RangeFacetCloudTest",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:60629/solr",
[junit4] 2> "node_name":"127.0.0.1:60629_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1242051 INFO (OverseerStateUpdate-72171581018865673-127.0.0.1:60628_solr-n_0000000000) [n:127.0.0.1:60628_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"org.apache.solr.search.facet.RangeFacetCloudTest",
[junit4] 2> "shard":"shard3",
[junit4] 2> "core":"org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:60628/solr",
[junit4] 2> "node_name":"127.0.0.1:60628_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1242092 INFO (OverseerStateUpdate-72171581018865673-127.0.0.1:60628_solr-n_0000000000) [n:127.0.0.1:60628_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"org.apache.solr.search.facet.RangeFacetCloudTest",
[junit4] 2> "shard":"shard4",
[junit4] 2> "core":"org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:60627/solr",
[junit4] 2> "node_name":"127.0.0.1:60627_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1242316 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=org.apache.solr.search.facet.RangeFacetCloudTest_configSet&newCollection=true&collection=org.apache.solr.search.facet.RangeFacetCloudTest&version=2&replicaType=NRT&property.config=solrconfig-minimal.xml&coreNodeName=core_node5&name=org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3&action=CREATE&numShards=4&shard=shard2&wt=javabin
[junit4] 2> 1242317 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=org.apache.solr.search.facet.RangeFacetCloudTest_configSet&newCollection=true&collection=org.apache.solr.search.facet.RangeFacetCloudTest&version=2&replicaType=NRT&property.config=solrconfig-minimal.xml&coreNodeName=core_node7&name=org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4&action=CREATE&numShards=4&shard=shard3&wt=javabin
[junit4] 2> 1242318 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
[junit4] 2> 1242318 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=org.apache.solr.search.facet.RangeFacetCloudTest_configSet&newCollection=true&collection=org.apache.solr.search.facet.RangeFacetCloudTest&version=2&replicaType=NRT&property.config=solrconfig-minimal.xml&coreNodeName=core_node8&name=org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6&action=CREATE&numShards=4&shard=shard4&wt=javabin
[junit4] 2> 1242322 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=org.apache.solr.search.facet.RangeFacetCloudTest_configSet&newCollection=true&collection=org.apache.solr.search.facet.RangeFacetCloudTest&version=2&replicaType=NRT&property.config=solrconfig-minimal.xml&coreNodeName=core_node2&name=org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1&action=CREATE&numShards=4&shard=shard1&wt=javabin
[junit4] 2> 1242391 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1242395 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1242395 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1242405 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 1242498 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 1242568 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 1242580 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 1243073 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 1244635 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1244652 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1244752 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1244760 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 1244904 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1' using configuration from configset org.apache.solr.search.facet.RangeFacetCloudTest_configSet, trusted=true
[junit4] 2> 1244905 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.c.CoreContainer Creating SolrCore 'org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6' using configuration from configset org.apache.solr.search.facet.RangeFacetCloudTest_configSet, trusted=true
[junit4] 2> 1244907 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60627.solr.core.org.apache.solr.search.facet.RangeFacetCloudTest.shard4.replica_n6' (registry 'solr.core.org.apache.solr.search.facet.RangeFacetCloudTest.shard4.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e3224bb
[junit4] 2> 1244907 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.c.SolrCore [[org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J2/temp/solr.search.facet.RangeFacetCloudTest_6856440F9C67FFC2-001/tempDir-001/node2/./org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J2/temp/solr.search.facet.RangeFacetCloudTest_6856440F9C67FFC2-001/tempDir-001/node2/./org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6/data/]
[junit4] 2> 1244908 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60630.solr.core.org.apache.solr.search.facet.RangeFacetCloudTest.shard1.replica_n1' (registry 'solr.core.org.apache.solr.search.facet.RangeFacetCloudTest.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e3224bb
[junit4] 2> 1244913 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.c.SolrCore [[org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J2/temp/solr.search.facet.RangeFacetCloudTest_6856440F9C67FFC2-001/tempDir-001/node3/./org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J2/temp/solr.search.facet.RangeFacetCloudTest_6856440F9C67FFC2-001/tempDir-001/node3/./org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1/data/]
[junit4] 2> 1244938 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=41.1181640625, floorSegmentMB=0.5791015625, forceMergeDeletesPctAllowed=19.926466641374027, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=48.8126482475713
[junit4] 2> 1244938 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=41.1181640625, floorSegmentMB=0.5791015625, forceMergeDeletesPctAllowed=19.926466641374027, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=48.8126482475713
[junit4] 2> 1245086 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.c.CoreContainer Creating SolrCore 'org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4' using configuration from configset org.apache.solr.search.facet.RangeFacetCloudTest_configSet, trusted=true
[junit4] 2> 1245089 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60628.solr.core.org.apache.solr.search.facet.RangeFacetCloudTest.shard3.replica_n4' (registry 'solr.core.org.apache.solr.search.facet.RangeFacetCloudTest.shard3.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e3224bb
[junit4] 2> 1245090 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.c.SolrCore [[org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J2/temp/solr.search.facet.RangeFacetCloudTest_6856440F9C67FFC2-001/tempDir-001/node4/./org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J2/temp/solr.search.facet.RangeFacetCloudTest_6856440F9C67FFC2-001/tempDir-001/node4/./org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4/data/]
[junit4] 2> 1245097 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=41.1181640625, floorSegmentMB=0.5791015625, forceMergeDeletesPctAllowed=19.926466641374027, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=48.8126482475713
[junit4] 2> 1245099 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.c.CoreContainer Creating SolrCore 'org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3' using configuration from configset org.apache.solr.search.facet.RangeFacetCloudTest_configSet, trusted=true
[junit4] 2> 1245099 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_60629.solr.core.org.apache.solr.search.facet.RangeFacetCloudTest.shard2.replica_n3' (registry 'solr.core.org.apache.solr.search.facet.RangeFacetCloudTest.shard2.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e3224bb
[junit4] 2> 1245099 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.c.SolrCore [[org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J2/temp/solr.search.facet.RangeFacetCloudTest_6856440F9C67FFC2-001/tempDir-001/node1/./org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J2/temp/solr.search.facet.RangeFacetCloudTest_6856440F9C67FFC2-001/tempDir-001/node1/./org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3/data/]
[junit4] 2> 1245108 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=30, maxMergedSegmentMB=41.1181640625, floorSegmentMB=0.5791015625, forceMergeDeletesPctAllowed=19.926466641374027, segmentsPerTier=10.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=48.8126482475713
[junit4] 2> 1245275 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1245275 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1245284 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1245284 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1245289 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=21, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1245293 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=21, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1245295 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@59207402[org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6] main]
[junit4] 2> 1245296 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@42c5de31[org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1] main]
[junit4] 2> 1245311 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/org.apache.solr.search.facet.RangeFacetCloudTest_configSet
[junit4] 2> 1245311 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/org.apache.solr.search.facet.RangeFacetCloudTest_configSet
[junit4] 2> 1245315 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/org.apache.solr.search.facet.RangeFacetCloudTest_configSet
[junit4] 2> 1245316 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/org.apache.solr.search.facet.RangeFacetCloudTest_configSet
[junit4] 2> 1245318 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1245323 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1245334 INFO (searcherExecutor-7384-thread-1-processing-n:127.0.0.1:60630_solr x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.c.SolrCore [org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1] Registered new searcher Searcher@42c5de31[org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1245342 INFO (searcherExecutor-7386-thread-1-processing-n:127.0.0.1:60627_solr x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.c.SolrCore [org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6] Registered new searcher Searcher@59207402[org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1245407 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/org.apache.solr.search.facet.RangeFacetCloudTest/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 1245410 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/org.apache.solr.search.facet.RangeFacetCloudTest/terms/shard4 to Terms{values={core_node8=0}, version=0}
[junit4] 2> 1245410 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/org.apache.solr.search.facet.RangeFacetCloudTest/leaders/shard4
[junit4] 2> 1245410 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/org.apache.solr.search.facet.RangeFacetCloudTest/leaders/shard1
[junit4] 2> 1245510 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1245510 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1245512 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=21, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1245513 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1245513 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1245515 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@208decd0[org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3] main]
[junit4] 2> 1245528 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=21, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 1245531 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@479f769c[org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4] main]
[junit4] 2> 1245531 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/org.apache.solr.search.facet.RangeFacetCloudTest_configSet
[junit4] 2> 1245539 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1245539 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1245539 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:60630/solr/org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1/
[junit4] 2> 1245540 ERROR (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.c.SyncStrategy No UpdateLog found - cannot sync
[junit4] 2> 1245540 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 1245540 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/org.apache.solr.search.facet.RangeFacetCloudTest/leaders/shard1/leader after winning as /collections/org.apache.solr.search.facet.RangeFacetCloudTest/leader_elect/shard1/election/72171581018865674-core_node2-n_0000000000
[junit4] 2> 1245540 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/org.apache.solr.search.facet.RangeFacetCloudTest_configSet
[junit4] 2> 1245541 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1245542 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1245542 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1245542 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:60627/solr/org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6/
[junit4] 2> 1245542 ERROR (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.c.SyncStrategy No UpdateLog found - cannot sync
[junit4] 2> 1245542 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 1245543 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/org.apache.solr.search.facet.RangeFacetCloudTest/leaders/shard4/leader after winning as /collections/org.apache.solr.search.facet.RangeFacetCloudTest/leader_elect/shard4/election/72171581018865672-core_node8-n_0000000000
[junit4] 2> 1245544 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/org.apache.solr.search.facet.RangeFacetCloudTest_configSet
[junit4] 2> 1245553 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/org.apache.solr.search.facet.RangeFacetCloudTest_configSet
[junit4] 2> 1245553 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1245568 INFO (searcherExecutor-7392-thread-1-processing-n:127.0.0.1:60629_solr x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.c.SolrCore [org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3] Registered new searcher Searcher@208decd0[org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1245569 INFO (searcherExecutor-7389-thread-1-processing-n:127.0.0.1:60628_solr x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.c.SolrCore [org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4] Registered new searcher Searcher@479f769c[org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1245573 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:60630/solr/org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1/ shard1
[junit4] 2> 1245583 INFO (zkCallback-7333-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.RangeFacetCloudTest/state.json] for collection [org.apache.solr.search.facet.RangeFacetCloudTest] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1245583 INFO (zkCallback-7355-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.RangeFacetCloudTest/state.json] for collection [org.apache.solr.search.facet.RangeFacetCloudTest] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1245584 INFO (zkCallback-7355-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.RangeFacetCloudTest/state.json] for collection [org.apache.solr.search.facet.RangeFacetCloudTest] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1245589 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:60627/solr/org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6/ shard4
[junit4] 2> 1245589 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard1 r:core_node2 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1245611 INFO (qtp1248916478-11900) [n:127.0.0.1:60630_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=org.apache.solr.search.facet.RangeFacetCloudTest_configSet&newCollection=true&collection=org.apache.solr.search.facet.RangeFacetCloudTest&version=2&replicaType=NRT&property.config=solrconfig-minimal.xml&coreNodeName=core_node2&name=org.apache.solr.search.facet.RangeFacetCloudTest_shard1_replica_n1&action=CREATE&numShards=4&shard=shard1&wt=javabin} status=0 QTime=3292
[junit4] 2> 1245624 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/org.apache.solr.search.facet.RangeFacetCloudTest/terms/shard2 to Terms{values={core_node5=0}, version=0}
[junit4] 2> 1245624 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/org.apache.solr.search.facet.RangeFacetCloudTest/leaders/shard2
[junit4] 2> 1245625 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/org.apache.solr.search.facet.RangeFacetCloudTest/terms/shard3 to Terms{values={core_node7=0}, version=0}
[junit4] 2> 1245633 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/org.apache.solr.search.facet.RangeFacetCloudTest/leaders/shard3
[junit4] 2> 1245698 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1245699 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1245699 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:60628/solr/org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4/
[junit4] 2> 1245700 ERROR (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.c.SyncStrategy No UpdateLog found - cannot sync
[junit4] 2> 1245700 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 1245701 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/org.apache.solr.search.facet.RangeFacetCloudTest/leaders/shard3/leader after winning as /collections/org.apache.solr.search.facet.RangeFacetCloudTest/leader_elect/shard3/election/72171581018865673-core_node7-n_0000000000
[junit4] 2> 1245710 INFO (zkCallback-7318-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.RangeFacetCloudTest/state.json] for collection [org.apache.solr.search.facet.RangeFacetCloudTest] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1245710 INFO (zkCallback-7355-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.RangeFacetCloudTest/state.json] for collection [org.apache.solr.search.facet.RangeFacetCloudTest] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1245710 INFO (zkCallback-7355-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.RangeFacetCloudTest/state.json] for collection [org.apache.solr.search.facet.RangeFacetCloudTest] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1245711 INFO (zkCallback-7355-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.RangeFacetCloudTest/state.json] for collection [org.apache.solr.search.facet.RangeFacetCloudTest] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1245714 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard4 r:core_node8 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1245722 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1245722 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1245722 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:60629/solr/org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3/
[junit4] 2> 1245722 ERROR (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.c.SyncStrategy No UpdateLog found - cannot sync
[junit4] 2> 1245722 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 1245722 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/org.apache.solr.search.facet.RangeFacetCloudTest/leaders/shard2/leader after winning as /collections/org.apache.solr.search.facet.RangeFacetCloudTest/leader_elect/shard2/election/72171581018865671-core_node5-n_0000000000
[junit4] 2> 1245732 INFO (qtp450148644-11889) [n:127.0.0.1:60627_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=org.apache.solr.search.facet.RangeFacetCloudTest_configSet&newCollection=true&collection=org.apache.solr.search.facet.RangeFacetCloudTest&version=2&replicaType=NRT&property.config=solrconfig-minimal.xml&coreNodeName=core_node8&name=org.apache.solr.search.facet.RangeFacetCloudTest_shard4_replica_n6&action=CREATE&numShards=4&shard=shard4&wt=javabin} status=0 QTime=3414
[junit4] 2> 1245740 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:60628/solr/org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4/ shard3
[junit4] 2> 1245763 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:60629/solr/org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3/ shard2
[junit4] 2> 1245890 INFO (zkCallback-7318-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.RangeFacetCloudTest/state.json] for collection [org.apache.solr.search.facet.RangeFacetCloudTest] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1245890 INFO (zkCallback-7333-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.RangeFacetCloudTest/state.json] for collection [org.apache.solr.search.facet.RangeFacetCloudTest] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1245891 INFO (zkCallback-7318-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.RangeFacetCloudTest/state.json] for collection [org.apache.solr.search.facet.RangeFacetCloudTest] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1245891 INFO (zkCallback-7355-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.RangeFacetCloudTest/state.json] for collection [org.apache.solr.search.facet.RangeFacetCloudTest] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1245891 INFO (zkCallback-7355-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.RangeFacetCloudTest/state.json] for collection [org.apache.solr.search.facet.RangeFacetCloudTest] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1245892 INFO (zkCallback-7355-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.RangeFacetCloudTest/state.json] for collection [org.apache.solr.search.facet.RangeFacetCloudTest] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1245892 INFO (zkCallback-7314-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.RangeFacetCloudTest/state.json] for collection [org.apache.solr.search.facet.RangeFacetCloudTest] has occurred - updating... (live nodes size: [4])
[junit4] 2> 1245898 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard3 r:core_node7 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard3_replica_n4 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1245899 INFO (qtp1508228138-11895) [n:127.0.0.1:60629_solr c:org.apache.solr.search.facet.RangeFacetCloudTest s:shard2 r:core_node5 x:org.apache.solr.search.facet.RangeFacetCloudTest_shard2_replica_n3 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1245944 INFO (qtp76931203-11891) [n:127.0.0.1:60628_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=org.apache.solr.search.facet.RangeFacetCloudTest
[...truncated too long message...]
e nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 1267143 INFO (jetty-closer-7403-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@44b704c0{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1267144 INFO (jetty-closer-7403-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2e73b238{/solr,null,UNAVAILABLE}
[junit4] 2> 1267144 INFO (jetty-closer-7403-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1267254 INFO (jetty-closer-7403-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1413440e{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1267254 INFO (jetty-closer-7403-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2dc79ea5{/solr,null,UNAVAILABLE}
[junit4] 2> 1267254 INFO (jetty-closer-7403-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1267366 INFO (jetty-closer-7403-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@57224966{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 1267367 INFO (jetty-closer-7403-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@38b5a5b2{/solr,null,UNAVAILABLE}
[junit4] 2> 1267367 INFO (jetty-closer-7403-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1267371 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1267593 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 4 /solr/packages.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 4 /solr/collections/org.apache.solr.search.facet.RangeFacetCloudTest/collectionprops.json
[junit4] 2> 4 /solr/configs/org.apache.solr.search.facet.RangeFacetCloudTest_configSet
[junit4] 2> 2 /solr/collections/org.apache.solr.search.facet.RangeFacetCloudTest/terms/shard1
[junit4] 2> 2 /solr/collections/org.apache.solr.search.facet.RangeFacetCloudTest/terms/shard2
[junit4] 2> 2 /solr/collections/org.apache.solr.search.facet.RangeFacetCloudTest/terms/shard3
[junit4] 2> 2 /solr/collections/org.apache.solr.search.facet.RangeFacetCloudTest/terms/shard4
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 57 /solr/collections/org.apache.solr.search.facet.RangeFacetCloudTest/state.json
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 26 /solr/overseer/queue
[junit4] 2> 20 /solr/live_nodes
[junit4] 2> 10 /solr/collections
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 1267601 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:60617
[junit4] 2> 1267601 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:60617
[junit4] 2> 1267601 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 60617
[junit4] 2> 1267602 INFO (SUITE-RangeFacetCloudTest-seed#[6856440F9C67FFC2]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> May 06, 2020 4:45:32 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> May 06, 2020 4:45:42 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.solr.search.facet.RangeFacetCloudTest:
[junit4] 2> 1) Thread[id=11838, name=SessionTracker, state=TIMED_WAITING, group=TGRP-RangeFacetCloudTest]
[junit4] 2> at java.base@14/java.lang.Thread.sleep(Native Method)
[junit4] 2> at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
[junit4] 2> May 06, 2020 4:45:42 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=11838, name=SessionTracker, state=TIMED_WAITING, group=TGRP-RangeFacetCloudTest]
[junit4] 2> 1277702 ERROR (SessionTracker) [ ] o.a.z.s.ZooKeeperCriticalThread Severe unrecoverable error, from thread : SessionTracker
[junit4] 2> => java.lang.InterruptedException: sleep interrupted
[junit4] 2> at java.base/java.lang.Thread.sleep(Native Method)
[junit4] 2> java.lang.InterruptedException: sleep interrupted
[junit4] 2> at java.lang.Thread.sleep(Native Method) [?:?]
[junit4] 2> at org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151) ~[zookeeper-3.5.7.jar:3.5.7]
[junit4] 2> May 06, 2020 4:45:42 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: All leaked threads terminated.
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {multiDefault=PostingsFormat(name=LuceneFixedGap), _root_=PostingsFormat(name=LuceneVarGapDocFreqInterval), id=PostingsFormat(name=Direct), facet_s=Lucene84}, docValues:{range_facet_l_dv=DocValuesFormat(name=Asserting), range_facet_i_dv=DocValuesFormat(name=Lucene80), intDvoDefault=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=490, maxMBSortInHeap=6.159100231367537, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@36b37d1b), locale=vai, timezone=US/Samoa
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/AdoptOpenJDK 14 (64-bit)/cpus=6,threads=1,free=228906048,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestDefaultStatsCache, TestTolerantUpdateProcessorCloud, TestSolrXml, HighlighterConfigTest, ConvertedLegacyTest, URLClassifyProcessorTest, DistanceFunctionTest, CoreAdminRequestStatusTest, TestZkAclsWithHadoopAuth, TestSubQueryTransformerCrossCore, TestSimpleTextCodec, TestCollectionAPIs, TestDFRSimilarityFactory, ChaosMonkeySafeLeaderWithPullReplicasTest, TestUseDocValuesAsStored2, SparseHLLTest, NestedAtomicUpdateTest, TestInfoStreamLogging, TestOverriddenPrefixQueryForCustomFieldType, TestSolrQueryResponse, TestSystemCollAutoCreate, TestCloudInspectUtil, TestUnifiedSolrHighlighter, AutoAddReplicasIntegrationTest, AdminHandlersProxyTest, SimplePostToolTest, DirectUpdateHandlerTest, TestBlobHandler, CloudMLTQParserTest, DeleteInactiveReplicaTest, HttpPartitionOnCommitTest, InfoHandlerTest, TestCodecSupport, HdfsAutoAddReplicasIntegrationTest, TestTrackingShardHandlerFactory, MoveReplicaHDFSFailoverTest, TestStressReorder, TestSearchPerf, AuditLoggerIntegrationTest, Tagger2Test, NestedShardedAtomicUpdateTest, DocExpirationUpdateProcessorFactoryTest, TestCloudRecovery2, SolrCmdDistributorTest, CdcrVersionReplicationTest, BasicDistributedZkTest, AuthToolTest, HdfsWriteToMultipleCollectionsTest, CertAuthPluginTest, TestCollectionsAPIViaSolrCloudCluster, DeleteStatusTest, CollectionsAPIAsyncDistributedZkTest, TestManagedSynonymFilterFactory, TestQueryUtils, SOLR749Test, TestConfigSetsAPIZkFailure, ActionThrottleTest, ReplicationFactorTest, NodeLostTriggerTest, ClassificationUpdateProcessorTest, TestDocumentBuilder, RangeFacetCloudTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=RangeFacetCloudTest -Dtests.seed=6856440F9C67FFC2 -Dtests.slow=true -Dtests.locale=vai -Dtests.timezone=US/Samoa -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J2 | RangeFacetCloudTest (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.search.facet.RangeFacetCloudTest:
[junit4] > 1) Thread[id=11838, name=SessionTracker, state=TIMED_WAITING, group=TGRP-RangeFacetCloudTest]
[junit4] > at java.base@14/java.lang.Thread.sleep(Native Method)
[junit4] > at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:151)
[junit4] > at __randomizedtesting.SeedInfo.seed([6856440F9C67FFC2]:0)
[junit4] Completed [357/911 (1!)] on J2 in 44.26s, 16 tests, 1 error <<< FAILURES!
[...truncated 46795 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/5983/consoleText
[repro] Revision: d4dbd0b9e75cb0bd5b0188a0f70070b6867fd94b
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseShenandoahGC"
[repro] Repro line: ant test -Dtestcase=RangeFacetCloudTest -Dtests.seed=6856440F9C67FFC2 -Dtests.slow=true -Dtests.locale=vai -Dtests.timezone=US/Samoa -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] RangeFacetCloudTest
[repro] ant compile-test
[...truncated 3152 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.RangeFacetCloudTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseShenandoahGC" -Dtests.seed=6856440F9C67FFC2 -Dtests.slow=true -Dtests.locale=vai -Dtests.timezone=US/Samoa -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[...truncated 156 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.search.facet.RangeFacetCloudTest
[repro] Exiting with code 0
[...truncated 73 lines...]
[JENKINS-Experimental-GC] Lucene-Solr-master-MacOSX (64bit/jdk-14)
- Build # 5984 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/5984/
Java: 64bit/jdk-14 -XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseShenandoahGC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.TestHighlightDedupGrouping
Error Message:
Suite timeout exceeded (>= 7200000 msec).
Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
at __randomizedtesting.SeedInfo.seed([19866F44C168A0BF]:0)
Build Log:
[...truncated 16034 lines...]
[junit4] Suite: org.apache.solr.TestHighlightDedupGrouping
[junit4] 2> 926912 INFO (SUITE-TestHighlightDedupGrouping-seed#[19866F44C168A0BF]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/server/solr/configsets/_default/conf'
[junit4] 2> 926912 INFO (SUITE-TestHighlightDedupGrouping-seed#[19866F44C168A0BF]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 926913 INFO (SUITE-TestHighlightDedupGrouping-seed#[19866F44C168A0BF]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping_19866F44C168A0BF-001/data-dir-102-001
[junit4] 2> 926913 WARN (SUITE-TestHighlightDedupGrouping-seed#[19866F44C168A0BF]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=56 numCloses=56
[junit4] 2> 926913 INFO (SUITE-TestHighlightDedupGrouping-seed#[19866F44C168A0BF]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 926914 INFO (SUITE-TestHighlightDedupGrouping-seed#[19866F44C168A0BF]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0) w/ MAC_OS_X supressed clientAuth
[junit4] 2> 926914 INFO (SUITE-TestHighlightDedupGrouping-seed#[19866F44C168A0BF]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 927443 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping_19866F44C168A0BF-001/tempDir-001/control/cores/collection1
[junit4] 2> 927446 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 927446 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 927446 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14+36
[junit4] 2> 927469 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 927469 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 927469 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 927470 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4b62cabe{/,null,AVAILABLE}
[junit4] 2> 927472 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@32fc57d{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:55714}
[junit4] 2> 927472 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.s.Server Started @927523ms
[junit4] 2> 927472 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=55714, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping_19866F44C168A0BF-001/tempDir-001/control/cores}
[junit4] 2> 927472 ERROR (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 927472 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 927472 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 927473 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 927473 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr
[junit4] 2> 927473 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-06T21:40:12.177044Z
[junit4] 2> 927473 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping_19866F44C168A0BF-001/tempDir-001/control/solr.xml
[junit4] 2> 927478 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@437595df, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 927621 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 927643 WARN (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7f0e3da2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 927643 WARN (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7f0e3da2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 927693 WARN (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@250a5306[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 927693 WARN (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@250a5306[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 927695 WARN (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 927795 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 927795 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 927854 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437595df
[junit4] 2> 927890 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437595df
[junit4] 2> 927890 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437595df
[junit4] 2> 927893 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping_19866F44C168A0BF-001/tempDir-001/control/cores
[junit4] 2> 927893 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 928076 INFO (coreLoadExecutor-8088-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 928263 INFO (coreLoadExecutor-8088-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 928338 INFO (coreLoadExecutor-8088-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 928346 INFO (coreLoadExecutor-8088-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 928346 INFO (coreLoadExecutor-8088-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437595df
[junit4] 2> 928347 INFO (coreLoadExecutor-8088-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping_19866F44C168A0BF-001/tempDir-001/control/cores/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping_19866F44C168A0BF-001/tempDir-001/control/cores/collection1/data/]
[junit4] 2> 928348 INFO (coreLoadExecutor-8088-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=26, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=59.076171875, floorSegmentMB=0.3505859375, forceMergeDeletesPctAllowed=27.17816330768003, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.47812313211141433, deletesPctAllowed=21.127546709234647
[junit4] 2> 928419 INFO (coreLoadExecutor-8088-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 928419 INFO (coreLoadExecutor-8088-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 928640 INFO (coreLoadExecutor-8088-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 928640 INFO (coreLoadExecutor-8088-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 928641 INFO (coreLoadExecutor-8088-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=50, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3663131501124596]
[junit4] 2> 928641 INFO (coreLoadExecutor-8088-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5af3ce18[collection1] main]
[junit4] 2> 928642 INFO (coreLoadExecutor-8088-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 928741 INFO (coreLoadExecutor-8088-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct, classname=DirectSolrSpellChecker, field=lowerfilt, minQueryLength=3}
[junit4] 2> 928911 INFO (coreLoadExecutor-8088-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 928911 INFO (coreLoadExecutor-8088-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1665978821367562240
[junit4] 2> 928912 INFO (searcherExecutor-8090-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 928914 INFO (searcherExecutor-8090-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 928914 INFO (searcherExecutor-8090-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 928914 INFO (searcherExecutor-8090-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 928916 INFO (searcherExecutor-8090-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 928918 INFO (searcherExecutor-8090-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 928919 INFO (searcherExecutor-8090-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 928920 INFO (searcherExecutor-8090-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 928920 INFO (searcherExecutor-8090-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 928921 INFO (searcherExecutor-8090-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5af3ce18[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 929326 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping_19866F44C168A0BF-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 929328 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 929328 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 929328 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14+36
[junit4] 2> 929330 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 929330 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 929330 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 929330 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6d3f12c0{/,null,AVAILABLE}
[junit4] 2> 929331 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3aed0471{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:55727}
[junit4] 2> 929331 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.s.Server Started @929382ms
[junit4] 2> 929331 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=55727, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping_19866F44C168A0BF-001/tempDir-001/shard0/cores}
[junit4] 2> 929332 ERROR (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 929332 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 929332 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 929332 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 929332 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr
[junit4] 2> 929332 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-06T21:40:14.036229Z
[junit4] 2> 929332 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping_19866F44C168A0BF-001/tempDir-001/shard0/solr.xml
[junit4] 2> 929336 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@437595df, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 929552 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 929555 WARN (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6fb8966b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 929555 WARN (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6fb8966b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 929561 WARN (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@191baa79[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 929561 WARN (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@191baa79[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 929563 WARN (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 929605 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 929605 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 929687 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437595df
[junit4] 2> 929741 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437595df
[junit4] 2> 929741 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437595df
[junit4] 2> 929743 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping_19866F44C168A0BF-001/tempDir-001/shard0/cores
[junit4] 2> 929743 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 929755 INFO (coreLoadExecutor-8107-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 929786 INFO (coreLoadExecutor-8107-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 930003 INFO (coreLoadExecutor-8107-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 930056 INFO (coreLoadExecutor-8107-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 930057 INFO (coreLoadExecutor-8107-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437595df
[junit4] 2> 930057 INFO (coreLoadExecutor-8107-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping_19866F44C168A0BF-001/tempDir-001/shard0/cores/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping_19866F44C168A0BF-001/tempDir-001/shard0/cores/collection1/data/]
[junit4] 2> 930073 INFO (coreLoadExecutor-8107-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=26, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=59.076171875, floorSegmentMB=0.3505859375, forceMergeDeletesPctAllowed=27.17816330768003, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.47812313211141433, deletesPctAllowed=21.127546709234647
[junit4] 2> 930371 INFO (coreLoadExecutor-8107-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 930371 INFO (coreLoadExecutor-8107-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 930373 INFO (coreLoadExecutor-8107-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 930373 INFO (coreLoadExecutor-8107-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 930374 INFO (coreLoadExecutor-8107-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=50, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3663131501124596]
[junit4] 2> 930374 INFO (coreLoadExecutor-8107-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@41e3e061[collection1] main]
[junit4] 2> 930375 INFO (coreLoadExecutor-8107-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 930399 INFO (coreLoadExecutor-8107-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct, classname=DirectSolrSpellChecker, field=lowerfilt, minQueryLength=3}
[junit4] 2> 930507 INFO (coreLoadExecutor-8107-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 930508 INFO (coreLoadExecutor-8107-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1665978823042138112
[junit4] 2> 930509 INFO (searcherExecutor-8109-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 930511 INFO (searcherExecutor-8109-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 930511 INFO (searcherExecutor-8109-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 930511 INFO (searcherExecutor-8109-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 930512 INFO (searcherExecutor-8109-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 930513 INFO (searcherExecutor-8109-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 930514 INFO (searcherExecutor-8109-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 930516 INFO (searcherExecutor-8109-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 930516 INFO (searcherExecutor-8109-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 930516 INFO (searcherExecutor-8109-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@41e3e061[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 930872 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping_19866F44C168A0BF-001/tempDir-001/shard1/cores/collection1
[junit4] 2> 930874 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 930874 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 930874 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14+36
[junit4] 2> 930877 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 930877 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 930877 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 930878 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@63445a18{/,null,AVAILABLE}
[junit4] 2> 930883 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@fe42d58{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:55733}
[junit4] 2> 930883 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.s.Server Started @930934ms
[junit4] 2> 930883 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=55733, coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping_19866F44C168A0BF-001/tempDir-001/shard1/cores}
[junit4] 2> 930884 ERROR (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 930884 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 930884 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 930884 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 930884 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr
[junit4] 2> 930884 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-06T21:40:15.588557Z
[junit4] 2> 930884 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping_19866F44C168A0BF-001/tempDir-001/shard1/solr.xml
[junit4] 2> 930890 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@437595df, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 930933 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 930937 WARN (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@58d4d4f1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 930937 WARN (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@58d4d4f1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 930941 WARN (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@acf0a33[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 930941 WARN (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@acf0a33[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 930943 WARN (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 930974 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 930975 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 931019 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437595df
[junit4] 2> 931037 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437595df
[junit4] 2> 931037 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437595df
[junit4] 2> 931041 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping_19866F44C168A0BF-001/tempDir-001/shard1/cores
[junit4] 2> 931041 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 931047 INFO (coreLoadExecutor-8126-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 931074 INFO (coreLoadExecutor-8126-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 931429 INFO (coreLoadExecutor-8126-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 931448 INFO (coreLoadExecutor-8126-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 931449 INFO (coreLoadExecutor-8126-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@437595df
[junit4] 2> 931449 INFO (coreLoadExecutor-8126-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping_19866F44C168A0BF-001/tempDir-001/shard1/cores/collection1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-master-MacOSX/solr/build/solr-core/test/J0/temp/solr.TestHighlightDedupGrouping_19866F44C168A0BF-001/tempDir-001/shard1/cores/collection1/data/]
[junit4] 2> 931451 INFO (coreLoadExecutor-8126-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=26, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=59.076171875, floorSegmentMB=0.3505859375, forceMergeDeletesPctAllowed=27.17816330768003, segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.47812313211141433, deletesPctAllowed=21.127546709234647
[junit4] 2> 931653 INFO (coreLoadExecutor-8126-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 931653 INFO (coreLoadExecutor-8126-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 931658 INFO (coreLoadExecutor-8126-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 931658 INFO (coreLoadExecutor-8126-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 931661 INFO (coreLoadExecutor-8126-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=50, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3663131501124596]
[junit4] 2> 931662 INFO (coreLoadExecutor-8126-thread-1) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@77f55e0d[collection1] main]
[junit4] 2> 931668 INFO (coreLoadExecutor-8126-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 931701 INFO (coreLoadExecutor-8126-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct, classname=DirectSolrSpellChecker, field=lowerfilt, minQueryLength=3}
[junit4] 2> 931816 INFO (coreLoadExecutor-8126-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 931817 INFO (coreLoadExecutor-8126-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1665978824414724096
[junit4] 2> 931819 INFO (searcherExecutor-8128-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 931820 INFO (searcherExecutor-8128-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 931820 INFO (searcherExecutor-8128-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 931820 INFO (searcherExecutor-8128-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 931820 INFO (searcherExecutor-8128-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 931821 INFO (searcherExecutor-8128-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 931821 INFO (searcherExecutor-8128-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 931822 INFO (searcherExecutor-8128-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 931822 INFO (searcherExecutor-8128-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 931823 INFO (searcherExecutor-8128-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@77f55e0d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 932330 INFO (TEST-TestHighlightDedupGrouping.test-seed#[19866F44C168A0BF]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 932334 INFO (qtp1329226265-12277) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1665978824955789312)} 0 1
[junit4] 2> 932338 INFO (qtp259352941-12298) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1665978824958935040)} 0 1
[junit4] 2> 932341 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1665978824962080768)} 0 1
[junit4] 2> 932342 INFO (qtp1329226265-12278) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1665978824965226496,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 932342 INFO (qtp1329226265-12278) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 932343 INFO (qtp1329226265-12278) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 932343 INFO (qtp1329226265-12278) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 1
[junit4] 2> 932343 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1665978824966275072,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 932343 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 932344 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 932344 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 0
[junit4] 2> 932345 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1665978824968372224,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 932345 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 932345 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 932345 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 0
[junit4] 2> 932347 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1665978824969420800)]} 0 1
[junit4] 2> 932348 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1665978824971517952,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 932348 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@37be9ad4 commitCommandVersion:1665978824971517952
[junit4] 2> 932357 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2f82eba7[collection1] main]
[junit4] 2> 932357 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 932360 INFO (searcherExecutor-8109-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2f82eba7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1588801217057, java.vendor=AdoptOpenJDK, os=Mac OS X, os.version=10.14.6, java.runtime.version=14+36, os.arch=x86_64, source=flush, lucene.version=9.0.0, java.vm.version=14+36, java.version=14}] :id=9sx0ekj3h255wqzqcot0v7dut)))}
[junit4] 2> 932360 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 12
[junit4] 2> 932362 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1665978824985149440)]} 0 1
[junit4] 2> 932363 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1665978824987246592,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 932363 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7e2c06a4 commitCommandVersion:1665978824987246592
[junit4] 2> 932366 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@61367831[collection1] main]
[junit4] 2> 932367 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 932369 INFO (searcherExecutor-8128-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@61367831[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(9.0.0):C1:[diagnostics={timestamp=1588801217069, java.vendor=AdoptOpenJDK, os=Mac OS X, os.version=10.14.6, java.runtime.version=14+36, os.arch=x86_64, source=flush, lucene.version=9.0.0, java.vm.version=14+36, java.version=14}] :id=9sx0ekj3h255wqzqcot0v7dux)))}
[junit4] 2> 932369 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 6
[junit4] 2> 932378 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&group.distributed.first=true&distrib=false&hl=false&group.limit=2&fl=id,score&shards.purpose=2048&start=0&shard.url=http://127.0.0.1:55733/collection1&rows=10&version=2&q=id_s1:1&omitHeader=false&NOW=1588801217074&isShard=true&hl.fl=id_s1&wt=javabin&group.field=id_s1&group=true} status=0 QTime=0
[junit4] 2> 932381 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&group.distributed.first=true&distrib=false&hl=false&group.limit=2&fl=id,score&shards.purpose=2048&start=0&shard.url=http://127.0.0.1:55727/collection1&rows=10&version=2&q=id_s1:1&omitHeader=false&NOW=1588801217074&isShard=true&hl.fl=id_s1&wt=javabin&group.field=id_s1&group=true} status=0 QTime=0
[junit4] 2> 932383 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={group.topgroups.id_s1=1&df=text&distrib=false&hl=false&group.limit=2&fl=id,score&shards.purpose=4&start=0&shard.url=http://127.0.0.1:55733/collection1&rows=10&version=2&group.distributed.second=true&q=id_s1:1&omitHeader=false&NOW=1588801217074&isShard=true&hl.fl=id_s1&wt=javabin&group.field=id_s1&group=true} status=0 QTime=0
[junit4] 2> 932383 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={group.topgroups.id_s1=1&df=text&distrib=false&hl=false&group.limit=2&fl=id,score&shards.purpose=4&start=0&shard.url=http://127.0.0.1:55727/collection1&rows=10&version=2&group.distributed.second=true&q=id_s1:1&omitHeader=false&NOW=1588801217074&isShard=true&hl.fl=id_s1&wt=javabin&group.field=id_s1&group=true} status=0 QTime=0
[junit4] 2> 932390 INFO (qtp259352941-12298) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&hl=true&group.limit=2&shards.purpose=192&shard.url=http://127.0.0.1:55727/collection1&version=2&q=id_s1:1&omitHeader=false&NOW=1588801217074&ids=1&isShard=true&hl.fl=id_s1&wt=javabin&group.field=id_s1} status=0 QTime=1
[junit4] 2> 932394 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={df=text&distrib=false&hl=true&group.limit=2&shards.purpose=192&shard.url=http://127.0.0.1:55733/collection1&version=2&q=id_s1:1&omitHeader=false&NOW=1588801217074&ids=1&isShard=true&hl.fl=id_s1&wt=javabin&group.field=id_s1} status=0 QTime=0
[junit4] 2> 932395 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.c.S.Request [collection1] webapp= path=/select params={q=id_s1:1&shards=http://127.0.0.1:55727/collection1,http://127.0.0.1:55733/collection1&hl=true&group.limit=2&hl.fl=id_s1&wt=javabin&version=2&group.field=id_s1&group=true} status=0 QTime=24
[junit4] 2> 932397 INFO (qtp1329226265-12279) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1665978825021849600)} 0 1
[junit4] 2> 932399 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2840614c[collection1] realtime]
[junit4] 2> 932400 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1665978825023946752)} 0 1
[junit4] 2> 932401 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4e81e1db[collection1] realtime]
[junit4] 2> 932401 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1665978825026043904)} 0 1
[junit4] 2> 932402 INFO (qtp1329226265-12276) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1665978825028141056,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 932402 INFO (qtp1329226265-12276) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 932403 INFO (qtp1329226265-12276) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 932403 INFO (qtp1329226265-12276) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 0
[junit4] 2> 932403 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1665978825029189632,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 932403 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@37be9ad4 commitCommandVersion:1665978825029189632
[junit4] 2> 932404 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@37728f7c[collection1] main]
[junit4] 2> 932404 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 932411 INFO (searcherExecutor-8109-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@37728f7c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 932412 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 8
[junit4] 2> 932415 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1665978825041772544,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 932415 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7e2c06a4 commitCommandVersion:1665978825041772544
[junit4] 2> 932417 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@26c09d48[collection1] main]
[junit4] 2> 932418 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 932420 INFO (searcherExecutor-8128-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@26c09d48[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 932420 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 5
[junit4] 2> 932422 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[0 (1665978825048064000)]} 0 1
[junit4] 2> 932424 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1 (1665978825050161152)]} 0 1
[junit4] 2> 932425 INFO (qtp259352941-12298) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2 (1665978825052258304)]} 0 0
[junit4] 2> 932426 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3 (1665978825053306880)]} 0 0
[junit4] 2> 932427 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4 (1665978825054355456)]} 0 0
[junit4] 2> 932434 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5 (1665978825060646912)]} 0 0
[junit4] 2> 932435 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6 (1665978825062744064)]} 0 0
[junit4] 2> 932436 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7 (1665978825063792640)]} 0 0
[junit4] 2> 932437 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[8 (1665978825064841216)]} 0 0
[junit4] 2> 932442 INFO (qtp259352941-12298) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[9 (1665978825065889792)]} 0 3
[junit4] 2> 932444 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[10 (1665978825072181248)]} 0 0
[junit4] 2> 932447 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[11 (1665978825074278400)]} 0 0
[junit4] 2> 932448 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[12 (1665978825076375552)]} 0 0
[junit4] 2> 932449 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[13 (1665978825077424128)]} 0 0
[junit4] 2> 932451 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[14 (1665978825078472704)]} 0 1
[junit4] 2> 932452 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[15 (1665978825080569856)]} 0 0
[junit4] 2> 932453 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[16 (1665978825081618432)]} 0 0
[junit4] 2> 932454 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[17 (1665978825082667008)]} 0 0
[junit4] 2> 932454 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[18 (1665978825082667009)]} 0 0
[junit4] 2> 932458 INFO (qtp259352941-12298) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[19 (1665978825086861312)]} 0 0
[junit4] 2> 932459 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[20 (1665978825087909888)]} 0 0
[junit4] 2> 932460 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[21 (1665978825088958464)]} 0 0
[junit4] 2> 932461 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[22 (1665978825090007040)]} 0 0
[junit4] 2> 932462 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[23 (1665978825090007041)]} 0 0
[junit4] 2> 932462 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[24 (1665978825091055616)]} 0 0
[junit4] 2> 932463 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[25 (1665978825092104192)]} 0 0
[junit4] 2> 932463 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[26 (1665978825092104192)]} 0 0
[junit4] 2> 932467 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[27 (1665978825096298496)]} 0 0
[junit4] 2> 932468 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[28 (1665978825097347072)]} 0 0
[junit4] 2> 932469 INFO (qtp259352941-12298) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[29 (1665978825098395648)]} 0 0
[junit4] 2> 932470 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[30 (1665978825099444224)]} 0 0
[junit4] 2> 932471 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[31 (1665978825100492800)]} 0 0
[junit4] 2> 932472 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[32 (1665978825101541376)]} 0 0
[junit4] 2> 932472 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[33 (1665978825101541376)]} 0 0
[junit4] 2> 932473 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[34 (1665978825102589952)]} 0 0
[junit4] 2> 932479 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[35 (1665978825108881408)]} 0 0
[junit4] 2> 932480 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[36 (1665978825109929984)]} 0 0
[junit4] 2> 932484 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[37 (1665978825113075712)]} 0 0
[junit4] 2> 932485 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[38 (1665978825115172864)]} 0 0
[junit4] 2> 932486 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[39 (1665978825116221440)]} 0 0
[junit4] 2> 932486 INFO (qtp259352941-12298) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[40 (1665978825116221440)]} 0 0
[junit4] 2> 932487 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[41 (1665978825117270016)]} 0 0
[junit4] 2> 932488 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[42 (1665978825117270017)]} 0 0
[junit4] 2> 932488 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[43 (1665978825118318592)]} 0 0
[junit4] 2> 932489 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[44 (1665978825119367168)]} 0 0
[junit4] 2> 932490 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[45 (1665978825119367168)]} 0 0
[junit4] 2> 932491 INFO (qtp259352941-12298) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[46 (1665978825121464320)]} 0 0
[junit4] 2> 932492 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[47 (1665978825121464320)]} 0 0
[junit4] 2> 932493 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[48 (1665978825123561472)]} 0 0
[junit4] 2> 932493 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[49 (1665978825123561472)]} 0 0
[junit4] 2> 932494 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[50 (1665978825124610048)]} 0 0
[junit4] 2> 932495 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[51 (1665978825124610049)]} 0 0
[junit4] 2> 932495 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[52 (1665978825125658624)]} 0 0
[junit4] 2> 932496 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[53 (1665978825125658625)]} 0 0
[junit4] 2> 932496 INFO (qtp259352941-12298) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[54 (1665978825126707200)]} 0 0
[junit4] 2> 932497 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[55 (1665978825126707200)]} 0 0
[junit4] 2> 932497 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[56 (1665978825127755776)]} 0 0
[junit4] 2> 932498 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[57 (1665978825127755776)]} 0 0
[junit4] 2> 932498 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[58 (1665978825128804352)]} 0 0
[junit4] 2> 932499 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[59 (1665978825129852928)]} 0 0
[junit4] 2> 932499 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[60 (1665978825129852928)]} 0 0
[junit4] 2> 932500 INFO (qtp259352941-12298) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[61 (1665978825129852929)]} 0 0
[junit4] 2> 932500 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[62 (1665978825130901504)]} 0 0
[junit4] 2> 932501 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[63 (1665978825131950080)]} 0 0
[junit4] 2> 932501 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[64 (1665978825131950081)]} 0 0
[junit4] 2> 932502 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[65 (1665978825132998656)]} 0 0
[junit4] 2> 932502 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[66 (1665978825132998657)]} 0 0
[junit4] 2> 932503 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[67 (1665978825134047232)]} 0 0
[junit4] 2> 932503 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[68 (1665978825134047232)]} 0 0
[junit4] 2> 932504 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[69 (1665978825135095808)]} 0 0
[junit4] 2> 932504 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[70 (1665978825135095808)]} 0 0
[junit4] 2> 932505 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[71 (1665978825136144384)]} 0 0
[junit4] 2> 932505 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[72 (1665978825136144384)]} 0 0
[junit4] 2> 932506 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[73 (1665978825137192960)]} 0 0
[junit4] 2> 932507 INFO (qtp259352941-12298) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[74 (1665978825138241536)]} 0 0
[junit4] 2> 932507 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[75 (1665978825138241537)]} 0 0
[junit4] 2> 932508 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[76 (1665978825139290112)]} 0 0
[junit4] 2> 932509 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[77 (1665978825140338688)]} 0 0
[junit4] 2> 932509 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[78 (1665978825140338689)]} 0 0
[junit4] 2> 932510 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[79 (1665978825141387264)]} 0 0
[junit4] 2> 932514 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[80 (1665978825141387264)]} 0 3
[junit4] 2> 932515 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[81 (1665978825146630144)]} 0 0
[junit4] 2> 932516 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[82 (1665978825147678720)]} 0 0
[junit4] 2> 932517 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[83 (1665978825148727296)]} 0 0
[junit4] 2> 932517 INFO (qtp259352941-12298) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[84 (1665978825148727296)]} 0 0
[junit4] 2> 932518 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[85 (1665978825149775872)]} 0 0
[junit4] 2> 932518 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[86 (1665978825149775873)]} 0 0
[junit4] 2> 932519 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[87 (1665978825150824448)]} 0 0
[junit4] 2> 932519 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[88 (1665978825150824449)]} 0 0
[junit4] 2> 932520 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[89 (1665978825151873024)]} 0 0
[junit4] 2> 932520 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[90 (1665978825151873025)]} 0 0
[junit4] 2> 932521 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[91 (1665978825151873026)]} 0 0
[junit4] 2> 932521 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[92 (1665978825152921600)]} 0 0
[junit4] 2> 932522 INFO (qtp259352941-12298) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[93 (1665978825153970176)]} 0 0
[junit4] 2> 932522 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[93 (1665978825153970176)]} 0 0
[junit4] 2> 932523 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[94 (1665978825153970177)]} 0 0
[junit4] 2> 932523 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[95 (1665978825155018752)]} 0 0
[junit4] 2> 932523 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[96 (1665978825155018752)]} 0 0
[junit4] 2> 932524 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[97 (1665978825156067328)]} 0 0
[junit4] 2> 932527 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[98 (1665978825156067328)]} 0 2
[junit4] 2> 932528 INFO (qtp259352941-12298) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[99 (1665978825160261632)]} 0 0
[junit4] 2> 932529 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[100 (1665978825161310208)]} 0 0
[junit4] 2> 932529 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[101 (1665978825161310209)]} 0 0
[junit4] 2> 932530 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[102 (1665978825162358784)]} 0 0
[junit4] 2> 932531 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[103 (1665978825162358784)]} 0 0
[junit4] 2> 932531 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[104 (1665978825163407360)]} 0 0
[junit4] 2> 932532 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[105 (1665978825163407360)]} 0 0
[junit4] 2> 932532 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[106 (1665978825164455936)]} 0 0
[junit4] 2> 932533 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[107 (1665978825165504512)]} 0 0
[junit4] 2> 932533 INFO (qtp259352941-12298) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[108 (1665978825165504513)]} 0 0
[junit4] 2> 932534 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[109 (1665978825166553088)]} 0 0
[junit4] 2> 932534 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[110 (1665978825166553089)]} 0 0
[junit4] 2> 932535 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[111 (1665978825167601664)]} 0 0
[junit4] 2> 932535 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[112 (1665978825167601665)]} 0 0
[junit4] 2> 932536 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[113 (1665978825168650240)]} 0 0
[junit4] 2> 932536 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[114 (1665978825168650240)]} 0 0
[junit4] 2> 932537 INFO (qtp259352941-12298) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[115 (1665978825169698816)]} 0 0
[junit4] 2> 932537 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[116 (1665978825169698816)]} 0 0
[junit4] 2> 932538 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[117 (1665978825170747392)]} 0 0
[junit4] 2> 932539 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[118 (1665978825170747392)]} 0 0
[junit4] 2> 932539 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[119 (1665978825171795968)]} 0 0
[junit4] 2> 932540 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[120 (1665978825171795968)]} 0 0
[junit4] 2> 932540 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[121 (1665978825172844544)]} 0 0
[junit4] 2> 932541 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1665978825172844544)]} 0 0
[junit4] 2> 932541 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[122 (1665978825173893120)]} 0 0
[junit4] 2> 932542 INFO (qtp259352941-12298) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[123 (1665978825174941696)]} 0 0
[junit4] 2> 932542 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[124 (1665978825174941696)]} 0 0
[junit4] 2> 932543 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[125 (1665978825174941697)]} 0 0
[junit4] 2> 932543 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[126 (1665978825175990272)]} 0 0
[junit4] 2> 932544 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[127 (1665978825175990272)]} 0 0
[junit4] 2> 932544 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[128 (1665978825177038848)]} 0 0
[junit4] 2> 932545 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[129 (1665978825177038849)]} 0 0
[junit4] 2> 932545 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[130 (1665978825178087424)]} 0 0
[junit4] 2> 932546 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[131 (1665978825178087425)]} 0 0
[junit4] 2> 932546 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[132 (1665978825179136000)]} 0 0
[junit4] 2> 932547 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[133 (1665978825180184576)]} 0 0
[junit4] 2> 932547 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[134 (1665978825180184576)]} 0 0
[junit4] 2> 932548 INFO (qtp259352941-12298) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[135 (1665978825181233152)]} 0 0
[junit4] 2> 932548 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[136 (1665978825181233152)]} 0 0
[junit4] 2> 932549 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[137 (1665978825182281728)]} 0 0
[junit4] 2> 932549 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[138 (1665978825182281729)]} 0 0
[junit4] 2> 932550 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[139 (1665978825183330304)]} 0 0
[junit4] 2> 932550 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[140 (1665978825183330304)]} 0 0
[junit4] 2> 932551 INFO (qtp259352941-12298) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[141 (1665978825184378880)]} 0 0
[junit4] 2> 932552 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[142 (1665978825185427456)]} 0 0
[junit4] 2> 932553 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[143 (1665978825185427457)]} 0 0
[junit4] 2> 932554 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[144 (1665978825186476032)]} 0 0
[junit4] 2> 932555 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[145 (1665978825188573184)]} 0 0
[junit4] 2> 932556 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[146 (1665978825189621760)]} 0 0
[junit4] 2> 932556 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[147 (1665978825189621761)]} 0 0
[junit4] 2> 932557 INFO (qtp259352941-12298) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[148 (1665978825190670336)]} 0 0
[junit4] 2> 932557 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[149 (1665978825190670337)]} 0 0
[junit4] 2> 932558 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[150 (1665978825191718912)]} 0 0
[junit4] 2> 932558 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[151 (1665978825191718913)]} 0 0
[junit4] 2> 932559 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[152 (1665978825192767488)]} 0 0
[junit4] 2> 932559 INFO (qtp259352941-12298) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[153 (1665978825192767488)]} 0 0
[junit4] 2> 932560 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[154 (1665978825192767489)]} 0 0
[junit4] 2> 932560 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[155 (1665978825193816064)]} 0 0
[junit4] 2> 932560 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[156 (1665978825193816064)]} 0 0
[junit4] 2> 932561 INFO (qtp312038083-12321) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[157 (1665978825194864640)]} 0 0
[junit4] 2> 932562 INFO (qtp312038083-12319) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[158 (1665978825194864641)]} 0 0
[junit4] 2> 932562 INFO (qtp259352941-12300) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[159 (1665978825195913216)]} 0 0
[junit4] 2> 932562 INFO (qtp259352941-12297) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[160 (1665978825195913217)]} 0 0
[junit4] 2> 932563 INFO (qtp259352941-12298) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[161 (1665978825196961792)]} 0 0
[junit4] 2> 932564 INFO (qtp312038083-12318) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[162 (1665978825196961792)]} 0 0
[junit4] 2> 932564 INFO (qtp312038083-12320) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[163 (1665978825198010368)]} 0 0
[junit4] 2> 932565 INFO (qtp259352941-12299) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[164 (1665978825199058944)]} 0 0
[junit4] 2> 932565 INFO (qtp312038083-12321) [ x:col
[...truncated too long message...]
@14/java.lang.Thread.run(Thread.java:832)
[junit4] 2>
[junit4] 2> ^^==============================================
[junit4] 2>
[junit4] 2> mai 07, 2020 6:40:11 NT??NG?? com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> mai 07, 2020 6:40:21 NT??NG?? com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.solr.TestHighlightDedupGrouping:
[junit4] 2> 1) Thread[id=12271, name=SUITE-TestHighlightDedupGrouping-seed#[19866F44C168A0BF]-worker, state=WAITING, group=TGRP-TestHighlightDedupGrouping]
[junit4] 2> at java.base@14/java.lang.Object.wait(Native Method)
[junit4] 2> at java.base@14/java.lang.Thread.join(Thread.java:1303)
[junit4] 2> at java.base@14/java.lang.Thread.join(Thread.java:1371)
[junit4] 2> at app//org.apache.solr.TestHighlightDedupGrouping.afterClass(TestHighlightDedupGrouping.java:42)
[junit4] 2> at java.base@14/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at java.base@14/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at java.base@14/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.base@14/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:905)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at app//org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at app//org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at app//org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at app//org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
[junit4] 2> at app//com.carrotsearch.randomizedtesting.ThreadLeakControl$$Lambda$146/0x0000000800c9b040.run(Unknown Source)
[junit4] 2> at java.base@14/java.lang.Thread.run(Thread.java:832)
[junit4] 2> mai 07, 2020 6:40:21 NT??NG?? com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=12271, name=SUITE-TestHighlightDedupGrouping-seed#[19866F44C168A0BF]-worker, state=WAITING, group=TGRP-TestHighlightDedupGrouping]
[junit4] 2> 8137041 INFO (SUITE-TestHighlightDedupGrouping-seed#[19866F44C168A0BF]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> May 06, 2020 11:40:22 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: All leaked threads terminated.
[junit4] 2> NOTE: test params are: codec=DummyCompressingStoredFieldsData(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY, chunkSize=16368, maxDocsPerChunk=1, blockShift=8), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, chunkSize=16368, blockSize=8)), sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@2a3f57dc), locale=ln-CG, timezone=Etc/GMT-7
[junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/AdoptOpenJDK 14 (64-bit)/cpus=6,threads=1,free=38556600,total=117440512
[junit4] 2> NOTE: All tests run in this JVM: [SolrPluginUtilsTest, TestJoin, TestCloudPseudoReturnFields, EmbeddedSolrNoSerializeTest, TestSolrTestCaseJ4, TestReplicaProperties, TestTestInjection, ZkCollectionPropsCachingTest, TestInitQParser, CollectionReloadTest, TestGraphMLResponseWriter, PreAnalyzedFieldTest, TestSweetSpotSimilarityFactory, DistributedFacetPivotLargeTest, CdcrUpdateLogTest, OrderedExecutorTest, TestTextField, SharedFSAutoReplicaFailoverTest, HdfsTlogReplayBufferedWhileIndexingTest, ReplaceNodeNoTargetTest, TestSmileRequest, TestSegmentSorting, TokenizerChainTest, HdfsLockFactoryTest, CustomTermsComponentTest, TestReqParamsAPI, NodeAddedTriggerTest, UnloadDistributedZkTest, DimensionalRoutedAliasUpdateProcessorTest, RegexBoostProcessorTest, TestJsonFacetsWithNestedObjects, TestConfigSetsAPI, TestClassicSimilarityFactory, RequestHandlersTest, TestStressLucene, CollectionStateFormat2Test, BJQFilterAccessibleTest, TestCoreBackup, DistributedSuggestComponentTest, SolrCoreMetricManagerTest, TestCloudConsistency, TestSurroundQueryParser, TestManagedResourceStorage, AnalysisAfterCoreReloadTest, XmlUpdateRequestHandlerTest, ByteBuffersDirectoryFactoryTest, TestExactStatsCache, TestCloudNestedDocsSort, TestSolrDeletionPolicy2, TestMultiWordSynonyms, SchemaVersionSpecificBehaviorTest, TestOrdValues, TestRangeQuery, TestHalfAndHalfDocValues, OverseerSolrResponseUnsafeSerializationTest, XmlInterpolationTest, OverriddenZkACLAndCredentialsProvidersTest, TestDistribDocBasedVersion, MultiSolrCloudTestCaseTest, RootFieldTest, SegmentsInfoRequestHandlerTest, TestCloudJSONFacetSKG, TestBulkSchemaConcurrent, TestLeaderElectionZkExpiry, TestNumericTerms32, SuggesterTSTTest, TimeRoutedAliasUpdateProcessorTest, TestUpdate, TestAuthorizationFramework, TestHttpShardHandlerFactory, CoreAdminHandlerTest, StatelessScriptUpdateProcessorFactoryTest, UninvertDocValuesMergePolicyTest, TestExactStatsCacheCloud, TestSchemaNameResource, TestSolrFieldCacheBean, HttpSolrCallGetCoreTest, MigrateRouteKeyTest, DateFieldTest, SplitShardTest, TestTlogReplayVsRecovery, TestImpersonationWithHadoopAuth, TestPhraseSuggestions, TestHttpServletCarrier, TriggerSetPropertiesIntegrationTest, LeaderElectionContextKeyTest, ShardsWhitelistTest, TestBackupRepositoryFactory, BasicAuthOnSingleNodeTest, TestUniqueKeyFieldResource, IgnoreLargeDocumentProcessorFactoryTest, LeaderTragicEventTest, BasicFunctionalityTest, ConvertedLegacyTest, CursorPagingTest, DisMaxRequestHandlerTest, DistributedIntervalFacetingTest, SolrInfoBeanTest, TestDistributedMissingSort, TestDistributedSearch, TestDocumentBuilder, TestGroupingSearch, TestHighlightDedupGrouping]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestHighlightDedupGrouping -Dtests.seed=19866F44C168A0BF -Dtests.slow=true -Dtests.locale=ln-CG -Dtests.timezone=Etc/GMT-7 -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.00s J0 | TestHighlightDedupGrouping (suite) <<<
[junit4] > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4] > at __randomizedtesting.SeedInfo.seed([19866F44C168A0BF]:0)
[junit4] Completed [911/911 (1!)] on J0 in 7210.66s, 1 test, 1 error <<< FAILURES!
[...truncated 44806 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-MacOSX/5984/consoleText
[repro] Revision: d4dbd0b9e75cb0bd5b0188a0f70070b6867fd94b
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseShenandoahGC"
[repro] Repro line: ant test -Dtestcase=TestHighlightDedupGrouping -Dtests.seed=19866F44C168A0BF -Dtests.slow=true -Dtests.locale=ln-CG -Dtests.timezone=Etc/GMT-7 -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TestHighlightDedupGrouping
[repro] ant compile-test
[...truncated 3152 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestHighlightDedupGrouping" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseShenandoahGC" -Dtests.seed=19866F44C168A0BF -Dtests.slow=true -Dtests.locale=ln-CG -Dtests.timezone=Etc/GMT-7 -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[...truncated 81 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.TestHighlightDedupGrouping
[repro] Exiting with code 0
[...truncated 73 lines...]