Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/1599/ Java: 64bit/jdk-11.0.6 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.response.transform.TestSubQueryTransformerDistrib Error Message: 1 thread leaked from SUITE scope at org.apache.solr.response.transform.TestSubQueryTransformerDistrib: 1) Thread[id=5270, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestSubQueryTransformerDistrib] at [email protected]/java.lang.Thread.sleep(Native Method) at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:157) Stack Trace: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.response.transform.TestSubQueryTransformerDistrib: 1) Thread[id=5270, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestSubQueryTransformerDistrib] at [email protected]/java.lang.Thread.sleep(Native Method) at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:157) at __randomizedtesting.SeedInfo.seed([7E1E1C4573D482B9]:0) Build Log: [...truncated 13856 lines...] [junit4] Suite: org.apache.solr.response.transform.TestSubQueryTransformerDistrib [junit4] 2> 376956 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/server/solr/configsets/_default/conf' [junit4] 2> 376956 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.response.transform.TestSubQueryTransformerDistrib_7E1E1C4573D482B9-001/data-dir-26-001 [junit4] 2> 376956 WARN (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1 [junit4] 2> 376957 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 376958 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="None") w/ MAC_OS_X supressed clientAuth [junit4] 2> 376958 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 376958 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 5 servers in /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.response.transform.TestSubQueryTransformerDistrib_7E1E1C4573D482B9-001/tempDir-001 [junit4] 2> 376960 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 376960 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0 [junit4] 2> 376961 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 376964 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0. [junit4] 2> 377061 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 61446 [junit4] 2> 377061 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:61446 [junit4] 2> 377061 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:61446 [junit4] 2> 377061 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 61446 [junit4] 2> 377065 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 377073 INFO (zkConnectionManagerCallback-3255-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 377073 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 377082 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 377086 INFO (zkConnectionManagerCallback-3257-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 377087 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 377089 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 377094 INFO (zkConnectionManagerCallback-3259-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 377094 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 377103 WARN (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker-SendThread(127.0.0.1:61446)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100a114e1220002. [junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e1220002, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) [junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e1220002, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2] [junit4] 2> 377207 WARN (jetty-launcher-3260-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23 [junit4] 2> 377207 INFO (jetty-launcher-3260-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 377207 INFO (jetty-launcher-3260-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 377207 INFO (jetty-launcher-3260-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.6+10 [junit4] 2> 377207 WARN (jetty-launcher-3260-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23 [junit4] 2> 377207 INFO (jetty-launcher-3260-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 377207 INFO (jetty-launcher-3260-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 377207 INFO (jetty-launcher-3260-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.6+10 [junit4] 2> 377212 WARN (jetty-launcher-3260-thread-3) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23 [junit4] 2> 377212 INFO (jetty-launcher-3260-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 377213 INFO (jetty-launcher-3260-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 377213 INFO (jetty-launcher-3260-thread-3) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.6+10 [junit4] 2> 377214 WARN (jetty-launcher-3260-thread-5) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23 [junit4] 2> 377214 INFO (jetty-launcher-3260-thread-5) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 377214 INFO (jetty-launcher-3260-thread-5) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 377214 INFO (jetty-launcher-3260-thread-5) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.6+10 [junit4] 2> 377214 WARN (jetty-launcher-3260-thread-4) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23 [junit4] 2> 377214 INFO (jetty-launcher-3260-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0) [junit4] 2> 377214 INFO (jetty-launcher-3260-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ... [junit4] 2> 377214 INFO (jetty-launcher-3260-thread-4) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.6+10 [junit4] 2> 377217 INFO (jetty-launcher-3260-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 377217 INFO (jetty-launcher-3260-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 377217 INFO (jetty-launcher-3260-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 377223 INFO (jetty-launcher-3260-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 377223 INFO (jetty-launcher-3260-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 377223 INFO (jetty-launcher-3260-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 377225 INFO (jetty-launcher-3260-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 377225 INFO (jetty-launcher-3260-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 377225 INFO (jetty-launcher-3260-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 377225 INFO (jetty-launcher-3260-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@57327df6{/solr,null,AVAILABLE} [junit4] 2> 377226 INFO (jetty-launcher-3260-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f4a8b56{/solr,null,AVAILABLE} [junit4] 2> 377227 INFO (jetty-launcher-3260-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1aa62c5a{/solr,null,AVAILABLE} [junit4] 2> 377228 INFO (jetty-launcher-3260-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5a8f685b{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:61453} [junit4] 2> 377228 INFO (jetty-launcher-3260-thread-1) [ ] o.e.j.s.Server Started @377329ms [junit4] 2> 377228 INFO (jetty-launcher-3260-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61453} [junit4] 2> 377228 INFO (jetty-launcher-3260-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@dcdcc95{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:61454} [junit4] 2> 377228 INFO (jetty-launcher-3260-thread-3) [ ] o.e.j.s.Server Started @377329ms [junit4] 2> 377228 ERROR (jetty-launcher-3260-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 377228 INFO (jetty-launcher-3260-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 377228 INFO (jetty-launcher-3260-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61454} [junit4] 2> 377228 INFO (jetty-launcher-3260-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.8.0 [junit4] 2> 377228 INFO (jetty-launcher-3260-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 377228 INFO (jetty-launcher-3260-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr [junit4] 2> 377228 INFO (jetty-launcher-3260-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-22T15:51:32.552754Z [junit4] 2> 377228 ERROR (jetty-launcher-3260-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 377228 INFO (jetty-launcher-3260-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 377228 INFO (jetty-launcher-3260-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.8.0 [junit4] 2> 377229 INFO (jetty-launcher-3260-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 377229 INFO (jetty-launcher-3260-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr [junit4] 2> 377229 INFO (jetty-launcher-3260-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-22T15:51:32.553044Z [junit4] 2> 377229 INFO (jetty-launcher-3260-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@606fb168{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:61455} [junit4] 2> 377229 INFO (jetty-launcher-3260-thread-2) [ ] o.e.j.s.Server Started @377330ms [junit4] 2> 377229 INFO (jetty-launcher-3260-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61455} [junit4] 2> 377229 ERROR (jetty-launcher-3260-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 377229 INFO (jetty-launcher-3260-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 377229 INFO (jetty-launcher-3260-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.8.0 [junit4] 2> 377229 INFO (jetty-launcher-3260-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 377229 INFO (jetty-launcher-3260-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr [junit4] 2> 377229 INFO (jetty-launcher-3260-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-22T15:51:32.553780Z [junit4] 2> 377231 INFO (jetty-launcher-3260-thread-5) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 377231 INFO (jetty-launcher-3260-thread-5) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 377231 INFO (jetty-launcher-3260-thread-5) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 377232 INFO (jetty-launcher-3260-thread-5) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4e4bcd44{/solr,null,AVAILABLE} [junit4] 2> 377233 INFO (jetty-launcher-3260-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 377233 INFO (jetty-launcher-3260-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 377234 INFO (jetty-launcher-3260-thread-5) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6f2c6f17{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:61456} [junit4] 2> 377234 INFO (jetty-launcher-3260-thread-5) [ ] o.e.j.s.Server Started @377335ms [junit4] 2> 377234 INFO (jetty-launcher-3260-thread-5) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61456} [junit4] 2> 377234 INFO (jetty-launcher-3260-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 377234 ERROR (jetty-launcher-3260-thread-5) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 377234 INFO (jetty-launcher-3260-thread-5) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 377234 INFO (jetty-launcher-3260-thread-5) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.8.0 [junit4] 2> 377234 INFO (jetty-launcher-3260-thread-5) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 377234 INFO (jetty-launcher-3260-thread-5) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr [junit4] 2> 377234 INFO (jetty-launcher-3260-thread-5) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-22T15:51:32.558867Z [junit4] 2> 377238 INFO (jetty-launcher-3260-thread-5) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 377238 INFO (jetty-launcher-3260-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 377238 INFO (jetty-launcher-3260-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 377239 INFO (jetty-launcher-3260-thread-4) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 377240 INFO (jetty-launcher-3260-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2e8ed35a{/solr,null,AVAILABLE} [junit4] 2> 377242 INFO (jetty-launcher-3260-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4d16b2ad{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:61460} [junit4] 2> 377242 INFO (jetty-launcher-3260-thread-4) [ ] o.e.j.s.Server Started @377343ms [junit4] 2> 377242 INFO (jetty-launcher-3260-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61460} [junit4] 2> 377243 ERROR (jetty-launcher-3260-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 377243 INFO (jetty-launcher-3260-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 377243 INFO (jetty-launcher-3260-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.8.0 [junit4] 2> 377243 INFO (jetty-launcher-3260-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 377243 INFO (zkConnectionManagerCallback-3262-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 377243 INFO (jetty-launcher-3260-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 377243 INFO (zkConnectionManagerCallback-3264-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 377244 INFO (jetty-launcher-3260-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 377243 INFO (jetty-launcher-3260-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr [junit4] 2> 377244 INFO (zkConnectionManagerCallback-3266-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 377244 INFO (jetty-launcher-3260-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-10-22T15:51:32.568236Z [junit4] 2> 377244 INFO (jetty-launcher-3260-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 377246 INFO (zkConnectionManagerCallback-3268-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 377246 INFO (jetty-launcher-3260-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 377246 INFO (jetty-launcher-3260-thread-5) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 377247 INFO (jetty-launcher-3260-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 377247 INFO (jetty-launcher-3260-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 377248 INFO (jetty-launcher-3260-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 377249 INFO (jetty-launcher-3260-thread-5) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 377265 INFO (zkConnectionManagerCallback-3270-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 377265 INFO (jetty-launcher-3260-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 377266 INFO (jetty-launcher-3260-thread-1) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled. [junit4] 2> 377266 INFO (jetty-launcher-3260-thread-3) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled. [junit4] 2> 377268 INFO (jetty-launcher-3260-thread-2) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled. [junit4] 2> 377270 INFO (jetty-launcher-3260-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 377272 WARN (jetty-launcher-3260-thread-1-SendThread(127.0.0.1:61446)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100a114e1220005. [junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e1220005, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) [junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e1220005, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2] [junit4] 2> 377272 WARN (jetty-launcher-3260-thread-3-SendThread(127.0.0.1:61446)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100a114e1220003. [junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e1220003, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) [junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e1220003, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2] [junit4] 2> 377273 WARN (jetty-launcher-3260-thread-2-SendThread(127.0.0.1:61446)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100a114e1220004. [junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e1220004, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) [junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e1220004, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2] [junit4] 2> 377274 INFO (jetty-launcher-3260-thread-5) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled. [junit4] 2> 377275 INFO (jetty-launcher-3260-thread-4) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled. [junit4] 2> 377277 WARN (jetty-launcher-3260-thread-4-SendThread(127.0.0.1:61446)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100a114e1220007. [junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e1220007, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) [junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e1220007, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2] [junit4] 2> 377277 WARN (jetty-launcher-3260-thread-5-SendThread(127.0.0.1:61446)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100a114e1220006. [junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e1220006, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) [junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e1220006, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2] [junit4] 2> 377469 INFO (jetty-launcher-3260-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 377477 WARN (jetty-launcher-3260-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@67c86ce9[provider=null,keyStore=null,trustStore=null] [junit4] 2> 377478 WARN (jetty-launcher-3260-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@67c86ce9[provider=null,keyStore=null,trustStore=null] [junit4] 2> 377485 WARN (jetty-launcher-3260-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6968bcc6[provider=null,keyStore=null,trustStore=null] [junit4] 2> 377485 WARN (jetty-launcher-3260-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6968bcc6[provider=null,keyStore=null,trustStore=null] [junit4] 2> 377486 INFO (jetty-launcher-3260-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61446/solr [junit4] 2> 377490 INFO (jetty-launcher-3260-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 377495 INFO (zkConnectionManagerCallback-3283-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 377495 INFO (jetty-launcher-3260-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 377500 WARN (jetty-launcher-3260-thread-1-SendThread(127.0.0.1:61446)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100a114e1220008. [junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e1220008, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) [junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e1220008, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2] [junit4] 2> 377537 INFO (jetty-launcher-3260-thread-5) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 377542 WARN (jetty-launcher-3260-thread-5) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@701a70bc[provider=null,keyStore=null,trustStore=null] [junit4] 2> 377546 WARN (jetty-launcher-3260-thread-5) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@701a70bc[provider=null,keyStore=null,trustStore=null] [junit4] 2> 377555 WARN (jetty-launcher-3260-thread-5) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5e9357f9[provider=null,keyStore=null,trustStore=null] [junit4] 2> 377555 WARN (jetty-launcher-3260-thread-5) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5e9357f9[provider=null,keyStore=null,trustStore=null] [junit4] 2> 377556 INFO (jetty-launcher-3260-thread-5) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61446/solr [junit4] 2> 377560 INFO (jetty-launcher-3260-thread-5) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 377562 INFO (zkConnectionManagerCallback-3290-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 377563 INFO (jetty-launcher-3260-thread-5) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 377609 INFO (jetty-launcher-3260-thread-1) [n:127.0.0.1:61453_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 377611 WARN (jetty-launcher-3260-thread-5-SendThread(127.0.0.1:61446)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100a114e1220009. [junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e1220009, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) [junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e1220009, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2] [junit4] 2> 377614 INFO (zkConnectionManagerCallback-3295-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 377614 INFO (jetty-launcher-3260-thread-1) [n:127.0.0.1:61453_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 377766 INFO (jetty-launcher-3260-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 377770 WARN (jetty-launcher-3260-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@155c1c91[provider=null,keyStore=null,trustStore=null] [junit4] 2> 377772 WARN (jetty-launcher-3260-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@155c1c91[provider=null,keyStore=null,trustStore=null] [junit4] 2> 377778 WARN (jetty-launcher-3260-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@13d848d1[provider=null,keyStore=null,trustStore=null] [junit4] 2> 377778 WARN (jetty-launcher-3260-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@13d848d1[provider=null,keyStore=null,trustStore=null] [junit4] 2> 377779 INFO (jetty-launcher-3260-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61446/solr [junit4] 2> 377782 INFO (jetty-launcher-3260-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 377785 INFO (zkConnectionManagerCallback-3310-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 377786 INFO (jetty-launcher-3260-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 377809 INFO (jetty-launcher-3260-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 377811 WARN (jetty-launcher-3260-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4a5aa526[provider=null,keyStore=null,trustStore=null] [junit4] 2> 377811 WARN (jetty-launcher-3260-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4a5aa526[provider=null,keyStore=null,trustStore=null] [junit4] 2> 377817 WARN (jetty-launcher-3260-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@22b3fae4[provider=null,keyStore=null,trustStore=null] [junit4] 2> 377817 WARN (jetty-launcher-3260-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@22b3fae4[provider=null,keyStore=null,trustStore=null] [junit4] 2> 377817 INFO (jetty-launcher-3260-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61446/solr [junit4] 2> 377820 INFO (jetty-launcher-3260-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 377824 INFO (zkConnectionManagerCallback-3317-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 377824 INFO (jetty-launcher-3260-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 377829 WARN (jetty-launcher-3260-thread-2-SendThread(127.0.0.1:61446)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100a114e122000c. [junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e122000c, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) [junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e122000c, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2] [junit4] 2> 377832 INFO (jetty-launcher-3260-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true] [junit4] 2> 377834 WARN (jetty-launcher-3260-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@37153827[provider=null,keyStore=null,trustStore=null] [junit4] 2> 377835 WARN (jetty-launcher-3260-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@37153827[provider=null,keyStore=null,trustStore=null] [junit4] 2> 377842 WARN (jetty-launcher-3260-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@502724ef[provider=null,keyStore=null,trustStore=null] [junit4] 2> 377842 WARN (jetty-launcher-3260-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@502724ef[provider=null,keyStore=null,trustStore=null] [junit4] 2> 377844 INFO (jetty-launcher-3260-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61446/solr [junit4] 2> 377846 INFO (jetty-launcher-3260-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 377849 INFO (zkConnectionManagerCallback-3324-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 377849 INFO (jetty-launcher-3260-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 377853 WARN (jetty-launcher-3260-thread-3-SendThread(127.0.0.1:61446)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100a114e122000d. [junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e122000d, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) [junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e122000d, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2] [junit4] 2> 377881 INFO (jetty-launcher-3260-thread-5) [n:127.0.0.1:61456_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 377885 INFO (zkConnectionManagerCallback-3326-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 377885 INFO (jetty-launcher-3260-thread-5) [n:127.0.0.1:61456_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 377950 INFO (jetty-launcher-3260-thread-5) [n:127.0.0.1:61456_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:61456_solr [junit4] 2> 377958 INFO (jetty-launcher-3260-thread-5) [n:127.0.0.1:61456_solr ] o.a.s.c.Overseer Overseer (id=72234705086447630-127.0.0.1:61456_solr-n_0000000000) starting [junit4] 2> 377985 INFO (jetty-launcher-3260-thread-3) [n:127.0.0.1:61454_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 377991 INFO (zkConnectionManagerCallback-3330-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 377996 INFO (jetty-launcher-3260-thread-3) [n:127.0.0.1:61454_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 378026 INFO (OverseerStateUpdate-72234705086447630-127.0.0.1:61456_solr-n_0000000000) [n:127.0.0.1:61456_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:61456_solr [junit4] 2> 378028 INFO (jetty-launcher-3260-thread-5) [n:127.0.0.1:61456_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61456_solr [junit4] 2> 378049 INFO (zkCallback-3325-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 378061 INFO (jetty-launcher-3260-thread-3) [n:127.0.0.1:61454_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 378062 INFO (jetty-launcher-3260-thread-5) [n:127.0.0.1:61456_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1 [junit4] 2> 378064 WARN (jetty-launcher-3260-thread-5) [n:127.0.0.1:61456_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> 378082 INFO (jetty-launcher-3260-thread-2) [n:127.0.0.1:61455_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 378089 INFO (zkConnectionManagerCallback-3339-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 378089 INFO (jetty-launcher-3260-thread-2) [n:127.0.0.1:61455_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 378125 INFO (jetty-launcher-3260-thread-5) [n:127.0.0.1:61456_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.response.transform.TestSubQueryTransformerDistrib_7E1E1C4573D482B9-001/tempDir-001/node5 [junit4] 2> 378153 INFO (jetty-launcher-3260-thread-3) [n:127.0.0.1:61454_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:61454_solr as DOWN [junit4] 2> 378183 INFO (jetty-launcher-3260-thread-3) [n:127.0.0.1:61454_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 378183 INFO (jetty-launcher-3260-thread-3) [n:127.0.0.1:61454_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61454_solr [junit4] 2> 378186 INFO (jetty-launcher-3260-thread-4) [n:127.0.0.1:61460_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 378197 INFO (zkConnectionManagerCallback-3346-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 378198 INFO (jetty-launcher-3260-thread-4) [n:127.0.0.1:61460_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 378209 INFO (zkCallback-3325-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 378210 INFO (zkCallback-3329-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 378227 INFO (jetty-launcher-3260-thread-2) [n:127.0.0.1:61455_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 378240 INFO (jetty-launcher-3260-thread-3) [n:127.0.0.1:61454_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1 [junit4] 2> 378240 WARN (jetty-launcher-3260-thread-3) [n:127.0.0.1:61454_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> 378302 INFO (jetty-launcher-3260-thread-3) [n:127.0.0.1:61454_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.response.transform.TestSubQueryTransformerDistrib_7E1E1C4573D482B9-001/tempDir-001/node3 [junit4] 2> 378322 INFO (jetty-launcher-3260-thread-4) [n:127.0.0.1:61460_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 378334 INFO (jetty-launcher-3260-thread-2) [n:127.0.0.1:61455_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:61455_solr as DOWN [junit4] 2> 378359 INFO (jetty-launcher-3260-thread-2) [n:127.0.0.1:61455_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 378360 INFO (jetty-launcher-3260-thread-2) [n:127.0.0.1:61455_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61455_solr [junit4] 2> 378379 INFO (zkCallback-3345-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 378379 INFO (zkCallback-3329-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 378380 INFO (zkCallback-3325-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 378380 INFO (zkCallback-3338-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 378414 INFO (jetty-launcher-3260-thread-2) [n:127.0.0.1:61455_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1 [junit4] 2> 378415 WARN (jetty-launcher-3260-thread-2) [n:127.0.0.1:61455_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> 378423 INFO (jetty-launcher-3260-thread-4) [n:127.0.0.1:61460_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:61460_solr as DOWN [junit4] 2> 378447 INFO (jetty-launcher-3260-thread-4) [n:127.0.0.1:61460_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 378447 INFO (jetty-launcher-3260-thread-4) [n:127.0.0.1:61460_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61460_solr [junit4] 2> 378454 INFO (jetty-launcher-3260-thread-2) [n:127.0.0.1:61455_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.response.transform.TestSubQueryTransformerDistrib_7E1E1C4573D482B9-001/tempDir-001/node2 [junit4] 2> 378466 INFO (zkCallback-3338-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 378466 INFO (zkCallback-3329-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 378467 INFO (zkCallback-3345-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 378467 INFO (zkCallback-3325-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 378478 INFO (jetty-launcher-3260-thread-4) [n:127.0.0.1:61460_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1 [junit4] 2> 378479 WARN (jetty-launcher-3260-thread-4) [n:127.0.0.1:61460_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> 378486 INFO (jetty-launcher-3260-thread-1) [n:127.0.0.1:61453_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 378496 INFO (jetty-launcher-3260-thread-4) [n:127.0.0.1:61460_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.response.transform.TestSubQueryTransformerDistrib_7E1E1C4573D482B9-001/tempDir-001/node4 [junit4] 2> 378498 INFO (jetty-launcher-3260-thread-1) [n:127.0.0.1:61453_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:61453_solr as DOWN [junit4] 2> 378502 INFO (jetty-launcher-3260-thread-1) [n:127.0.0.1:61453_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 378502 INFO (jetty-launcher-3260-thread-1) [n:127.0.0.1:61453_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61453_solr [junit4] 2> 378506 INFO (zkCallback-3345-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 378506 INFO (zkCallback-3338-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 378506 INFO (zkCallback-3329-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 378507 INFO (zkCallback-3325-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 378506 INFO (zkCallback-3294-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 378510 INFO (jetty-launcher-3260-thread-1) [n:127.0.0.1:61453_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1 [junit4] 2> 378511 WARN (jetty-launcher-3260-thread-1) [n:127.0.0.1:61453_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> 378530 INFO (jetty-launcher-3260-thread-1) [n:127.0.0.1:61453_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.response.transform.TestSubQueryTransformerDistrib_7E1E1C4573D482B9-001/tempDir-001/node1 [junit4] 2> 378538 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=5 [junit4] 2> 378539 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 378542 INFO (zkConnectionManagerCallback-3369-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 378542 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 378545 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5) [junit4] 2> 378549 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:61446/solr ready [junit4] 2> 379652 INFO (qtp1642831194-5337) [n:127.0.0.1:61456_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params property.schema=schema-docValuesJoin.xml&property.config=solrconfig-doctransformers.xml&collection.configName=solrCloudCollectionConfig&name=people&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 379663 INFO (OverseerThreadFactory-3337-thread-1-processing-n:127.0.0.1:61456_solr) [n:127.0.0.1:61456_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection people [junit4] 2> 379800 INFO (OverseerStateUpdate-72234705086447630-127.0.0.1:61456_solr-n_0000000000) [n:127.0.0.1:61456_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"people", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"people_shard1_replica_n1", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:61456/solr";, [junit4] 2> "node_name":"127.0.0.1:61456_solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 379806 INFO (OverseerStateUpdate-72234705086447630-127.0.0.1:61456_solr-n_0000000000) [n:127.0.0.1:61456_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"people", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"people_shard1_replica_n2", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:61460/solr";, [junit4] 2> "node_name":"127.0.0.1:61460_solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 379816 INFO (OverseerStateUpdate-72234705086447630-127.0.0.1:61456_solr-n_0000000000) [n:127.0.0.1:61456_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"people", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"people_shard2_replica_n4", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:61454/solr";, [junit4] 2> "node_name":"127.0.0.1:61454_solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 379826 INFO (OverseerStateUpdate-72234705086447630-127.0.0.1:61456_solr-n_0000000000) [n:127.0.0.1:61456_solr ] o.a.s.c.o.SliceMutator createReplica() { [junit4] 2> "operation":"ADDREPLICA", [junit4] 2> "collection":"people", [junit4] 2> "shard":"shard2", [junit4] 2> "core":"people_shard2_replica_n6", [junit4] 2> "state":"down", [junit4] 2> "base_url":"http://127.0.0.1:61455/solr";, [junit4] 2> "node_name":"127.0.0.1:61455_solr", [junit4] 2> "type":"NRT", [junit4] 2> "waitForFinalState":"false"} [junit4] 2> 380056 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr x:people_shard2_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=people&version=2&replicaType=NRT&property.schema=schema-docValuesJoin.xml&property.config=solrconfig-doctransformers.xml&coreNodeName=core_node7&name=people_shard2_replica_n4&action=CREATE&numShards=2&shard=shard2&wt=javabin [junit4] 2> 380057 INFO (qtp1137792167-5350) [n:127.0.0.1:61460_solr x:people_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=people&version=2&replicaType=NRT&property.schema=schema-docValuesJoin.xml&property.config=solrconfig-doctransformers.xml&coreNodeName=core_node5&name=people_shard1_replica_n2&action=CREATE&numShards=2&shard=shard1&wt=javabin [junit4] 2> 380058 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr x:people_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=people&version=2&replicaType=NRT&property.schema=schema-docValuesJoin.xml&property.config=solrconfig-doctransformers.xml&coreNodeName=core_node3&name=people_shard1_replica_n1&action=CREATE&numShards=2&shard=shard1&wt=javabin [junit4] 2> 380058 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr x:people_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores [junit4] 2> 380058 INFO (qtp1856525005-5323) [n:127.0.0.1:61455_solr x:people_shard2_replica_n6 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=people&version=2&replicaType=NRT&property.schema=schema-docValuesJoin.xml&property.config=solrconfig-doctransformers.xml&coreNodeName=core_node8&name=people_shard2_replica_n6&action=CREATE&numShards=2&shard=shard2&wt=javabin [junit4] 2> 380092 INFO (qtp1856525005-5323) [n:127.0.0.1:61455_solr c:people s:shard2 r:core_node8 x:people_shard2_replica_n6 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0 [junit4] 2> 380093 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0 [junit4] 2> 380096 INFO (qtp1137792167-5350) [n:127.0.0.1:61460_solr c:people s:shard1 r:core_node5 x:people_shard1_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0 [junit4] 2> 380096 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0 [junit4] 2> 380111 INFO (qtp1856525005-5323) [n:127.0.0.1:61455_solr c:people s:shard2 r:core_node8 x:people_shard2_replica_n6 ] o.a.s.s.IndexSchema Schema name=doc-values-for-Join [junit4] 2> 380112 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.s.IndexSchema Schema name=doc-values-for-Join [junit4] 2> 380115 INFO (qtp1856525005-5323) [n:127.0.0.1:61455_solr c:people s:shard2 r:core_node8 x:people_shard2_replica_n6 ] o.a.s.s.IndexSchema Loaded schema doc-values-for-Join/1.6 with uniqueid field id [junit4] 2> 380116 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.s.IndexSchema Loaded schema doc-values-for-Join/1.6 with uniqueid field id [junit4] 2> 380119 INFO (qtp1137792167-5350) [n:127.0.0.1:61460_solr c:people s:shard1 r:core_node5 x:people_shard1_replica_n2 ] o.a.s.s.IndexSchema Schema name=doc-values-for-Join [junit4] 2> 380123 INFO (qtp1137792167-5350) [n:127.0.0.1:61460_solr c:people s:shard1 r:core_node5 x:people_shard1_replica_n2 ] o.a.s.s.IndexSchema Loaded schema doc-values-for-Join/1.6 with uniqueid field id [junit4] 2> 380123 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=doc-values-for-Join [junit4] 2> 380127 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema doc-values-for-Join/1.6 with uniqueid field id [junit4] 2> 380140 INFO (qtp1856525005-5323) [n:127.0.0.1:61455_solr c:people s:shard2 r:core_node8 x:people_shard2_replica_n6 ] o.a.s.c.CoreContainer Creating SolrCore 'people_shard2_replica_n6' using configuration from configset solrCloudCollectionConfig, trusted=true [junit4] 2> 380140 INFO (qtp1856525005-5323) [n:127.0.0.1:61455_solr c:people s:shard2 r:core_node8 x:people_shard2_replica_n6 ] o.a.s.c.SolrCore [[people_shard2_replica_n6] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.response.transform.TestSubQueryTransformerDistrib_7E1E1C4573D482B9-001/tempDir-001/node2/people_shard2_replica_n6], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.response.transform.TestSubQueryTransformerDistrib_7E1E1C4573D482B9-001/tempDir-001/node2/people_shard2_replica_n6/data/] [junit4] 2> 380141 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.c.CoreContainer Creating SolrCore 'people_shard2_replica_n4' using configuration from configset solrCloudCollectionConfig, trusted=true [junit4] 2> 380141 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.c.SolrCore [[people_shard2_replica_n4] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.response.transform.TestSubQueryTransformerDistrib_7E1E1C4573D482B9-001/tempDir-001/node3/people_shard2_replica_n4], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.response.transform.TestSubQueryTransformerDistrib_7E1E1C4573D482B9-001/tempDir-001/node3/people_shard2_replica_n4/data/] [junit4] 2> 380148 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'people_shard1_replica_n1' using configuration from configset solrCloudCollectionConfig, trusted=true [junit4] 2> 380149 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.c.SolrCore [[people_shard1_replica_n1] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.response.transform.TestSubQueryTransformerDistrib_7E1E1C4573D482B9-001/tempDir-001/node5/people_shard1_replica_n1], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.response.transform.TestSubQueryTransformerDistrib_7E1E1C4573D482B9-001/tempDir-001/node5/people_shard1_replica_n1/data/] [junit4] 2> 380161 INFO (qtp1137792167-5350) [n:127.0.0.1:61460_solr c:people s:shard1 r:core_node5 x:people_shard1_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'people_shard1_replica_n2' using configuration from configset solrCloudCollectionConfig, trusted=true [junit4] 2> 380161 INFO (qtp1137792167-5350) [n:127.0.0.1:61460_solr c:people s:shard1 r:core_node5 x:people_shard1_replica_n2 ] o.a.s.c.SolrCore [[people_shard1_replica_n2] ] Opening new SolrCore at [/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.response.transform.TestSubQueryTransformerDistrib_7E1E1C4573D482B9-001/tempDir-001/node4/people_shard1_replica_n2], dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.response.transform.TestSubQueryTransformerDistrib_7E1E1C4573D482B9-001/tempDir-001/node4/people_shard1_replica_n2/data/] [junit4] 2> 380180 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 380180 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 380181 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 380181 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 380187 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/solrCloudCollectionConfig [junit4] 2> 380188 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/solrCloudCollectionConfig [junit4] 2> 380188 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 380188 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1681267774468390912 [junit4] 2> 380195 INFO (searcherExecutor-3373-thread-1-processing-n:127.0.0.1:61454_solr x:people_shard2_replica_n4 c:people s:shard2 r:core_node7) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.c.SolrCore [people_shard2_replica_n4] Registered new searcher autowarm time: 0 ms [junit4] 2> 380201 INFO (qtp1856525005-5323) [n:127.0.0.1:61455_solr c:people s:shard2 r:core_node8 x:people_shard2_replica_n6 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 380201 INFO (qtp1856525005-5323) [n:127.0.0.1:61455_solr c:people s:shard2 r:core_node8 x:people_shard2_replica_n6 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 380208 INFO (qtp1856525005-5323) [n:127.0.0.1:61455_solr c:people s:shard2 r:core_node8 x:people_shard2_replica_n6 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 380208 INFO (qtp1856525005-5323) [n:127.0.0.1:61455_solr c:people s:shard2 r:core_node8 x:people_shard2_replica_n6 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 380214 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/people/terms/shard2 to Terms{values={core_node7=0}, version=0} [junit4] 2> 380214 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/people/leaders/shard2 [junit4] 2> 380227 INFO (qtp1856525005-5323) [n:127.0.0.1:61455_solr c:people s:shard2 r:core_node8 x:people_shard2_replica_n6 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/solrCloudCollectionConfig [junit4] 2> 380231 INFO (qtp1856525005-5323) [n:127.0.0.1:61455_solr c:people s:shard2 r:core_node8 x:people_shard2_replica_n6 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/solrCloudCollectionConfig [junit4] 2> 380231 INFO (qtp1856525005-5323) [n:127.0.0.1:61455_solr c:people s:shard2 r:core_node8 x:people_shard2_replica_n6 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 380231 INFO (qtp1856525005-5323) [n:127.0.0.1:61455_solr c:people s:shard2 r:core_node8 x:people_shard2_replica_n6 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1681267774513479680 [junit4] 2> 380239 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard2: total=2 found=1 timeoute in=9996ms [junit4] 2> 380240 INFO (searcherExecutor-3371-thread-1-processing-n:127.0.0.1:61455_solr x:people_shard2_replica_n6 c:people s:shard2 r:core_node8) [n:127.0.0.1:61455_solr c:people s:shard2 r:core_node8 x:people_shard2_replica_n6 ] o.a.s.c.SolrCore [people_shard2_replica_n6] Registered new searcher autowarm time: 0 ms [junit4] 2> 380252 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 380252 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 380256 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 380256 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 380266 INFO (qtp1137792167-5350) [n:127.0.0.1:61460_solr c:people s:shard1 r:core_node5 x:people_shard1_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 380270 INFO (qtp1137792167-5350) [n:127.0.0.1:61460_solr c:people s:shard1 r:core_node5 x:people_shard1_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 380271 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/solrCloudCollectionConfig [junit4] 2> 380272 INFO (qtp1856525005-5323) [n:127.0.0.1:61455_solr c:people s:shard2 r:core_node8 x:people_shard2_replica_n6 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/people/terms/shard2 to Terms{values={core_node7=0, core_node8=0}, version=1} [junit4] 2> 380273 INFO (qtp1856525005-5323) [n:127.0.0.1:61455_solr c:people s:shard2 r:core_node8 x:people_shard2_replica_n6 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/people/leaders/shard2 [junit4] 2> 380277 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/solrCloudCollectionConfig [junit4] 2> 380277 INFO (qtp1137792167-5350) [n:127.0.0.1:61460_solr c:people s:shard1 r:core_node5 x:people_shard1_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 380277 INFO (qtp1137792167-5350) [n:127.0.0.1:61460_solr c:people s:shard1 r:core_node5 x:people_shard1_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 380277 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 380277 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1681267774561714176 [junit4] 2> 380298 INFO (qtp1137792167-5350) [n:127.0.0.1:61460_solr c:people s:shard1 r:core_node5 x:people_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/solrCloudCollectionConfig [junit4] 2> 380299 INFO (searcherExecutor-3375-thread-1-processing-n:127.0.0.1:61456_solr x:people_shard1_replica_n1 c:people s:shard1 r:core_node3) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.c.SolrCore [people_shard1_replica_n1] Registered new searcher autowarm time: 0 ms [junit4] 2> 380301 INFO (qtp1137792167-5350) [n:127.0.0.1:61460_solr c:people s:shard1 r:core_node5 x:people_shard1_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/solrCloudCollectionConfig [junit4] 2> 380301 INFO (qtp1137792167-5350) [n:127.0.0.1:61460_solr c:people s:shard1 r:core_node5 x:people_shard1_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms [junit4] 2> 380302 INFO (qtp1137792167-5350) [n:127.0.0.1:61460_solr c:people s:shard1 r:core_node5 x:people_shard1_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1681267774587928576 [junit4] 2> 380316 INFO (searcherExecutor-3377-thread-1-processing-n:127.0.0.1:61460_solr x:people_shard1_replica_n2 c:people s:shard1 r:core_node5) [n:127.0.0.1:61460_solr c:people s:shard1 r:core_node5 x:people_shard1_replica_n2 ] o.a.s.c.SolrCore [people_shard1_replica_n2] Registered new searcher autowarm time: 0 ms [junit4] 2> 380342 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/people/terms/shard1 to Terms{values={core_node3=0}, version=0} [junit4] 2> 380343 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/people/leaders/shard1 [junit4] 2> 380362 INFO (qtp1137792167-5350) [n:127.0.0.1:61460_solr c:people s:shard1 r:core_node5 x:people_shard1_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/people/terms/shard1 to Terms{values={core_node3=0, core_node5=0}, version=1} [junit4] 2> 380368 INFO (qtp1137792167-5350) [n:127.0.0.1:61460_solr c:people s:shard1 r:core_node5 x:people_shard1_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/people/leaders/shard1 [junit4] 2> 380408 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 380408 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 380408 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:61456/solr/people_shard1_replica_n1/ [junit4] 2> 380414 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=people_shard1_replica_n1 url=http://127.0.0.1:61456/solr START replicas=[http://127.0.0.1:61460/solr/people_shard1_replica_n2/] nUpdates=100 [junit4] 2> 380418 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.u.PeerSync PeerSync: core=people_shard1_replica_n1 url=http://127.0.0.1:61456/solr DONE. We have no versions. sync failed. [junit4] 2> 380424 INFO (qtp1137792167-5351) [n:127.0.0.1:61460_solr c:people s:shard1 r:core_node5 x:people_shard1_replica_n2 ] o.a.s.c.S.Request [people_shard1_replica_n2] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=5 [junit4] 2> 380426 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate [junit4] 2> 380426 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_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> 380426 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/people/leaders/shard1/leader after winning as /collections/people/leader_elect/shard1/election/72234705086447630-core_node3-n_0000000000 [junit4] 2> 380465 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:61456/solr/people_shard1_replica_n1/ shard1 [junit4] 2> 380582 INFO (zkCallback-3325-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 380591 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr c:people s:shard1 r:core_node3 x:people_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 380607 INFO (qtp1642831194-5334) [n:127.0.0.1:61456_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=people&version=2&replicaType=NRT&property.schema=schema-docValuesJoin.xml&property.config=solrconfig-doctransformers.xml&coreNodeName=core_node3&name=people_shard1_replica_n1&action=CREATE&numShards=2&shard=shard1&wt=javabin} status=0 QTime=549 [junit4] 2> 380711 INFO (zkCallback-3325-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 380711 INFO (zkCallback-3325-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 380748 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 380748 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 380748 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:61454/solr/people_shard2_replica_n4/ [junit4] 2> 380749 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.u.PeerSync PeerSync: core=people_shard2_replica_n4 url=http://127.0.0.1:61454/solr START replicas=[http://127.0.0.1:61455/solr/people_shard2_replica_n6/] nUpdates=100 [junit4] 2> 380750 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.u.PeerSync PeerSync: core=people_shard2_replica_n4 url=http://127.0.0.1:61454/solr DONE. We have no versions. sync failed. [junit4] 2> 380759 INFO (qtp1856525005-5322) [n:127.0.0.1:61455_solr c:people s:shard2 r:core_node8 x:people_shard2_replica_n6 ] o.a.s.c.S.Request [people_shard2_replica_n6] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=1 [junit4] 2> 380761 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate [junit4] 2> 380761 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_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> 380761 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/people/leaders/shard2/leader after winning as /collections/people/leader_elect/shard2/election/72234705086447631-core_node7-n_0000000000 [junit4] 2> 380770 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:61454/solr/people_shard2_replica_n4/ shard2 [junit4] 2> 380878 INFO (zkCallback-3329-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 380879 INFO (zkCallback-3325-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 380879 INFO (zkCallback-3325-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 380883 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr c:people s:shard2 r:core_node7 x:people_shard2_replica_n4 ] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 380888 INFO (qtp50999940-5330) [n:127.0.0.1:61454_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=people&version=2&replicaType=NRT&property.schema=schema-docValuesJoin.xml&property.config=solrconfig-doctransformers.xml&coreNodeName=core_node7&name=people_shard2_replica_n4&action=CREATE&numShards=2&shard=shard2&wt=javabin} status=0 QTime=833 [junit4] 2> 380991 INFO (zkCallback-3325-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 380991 INFO (zkCallback-3325-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 380991 INFO (zkCallback-3329-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 380991 INFO (zkCallback-3329-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 381310 INFO (qtp1856525005-5323) [n:127.0.0.1:61455_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=people&version=2&replicaType=NRT&property.schema=schema-docValuesJoin.xml&property.config=solrconfig-doctransformers.xml&coreNodeName=core_node8&name=people_shard2_replica_n6&action=CREATE&numShards=2&shard=shard2&wt=javabin} status=0 QTime=1251 [junit4] 2> 381418 INFO (zkCallback-3325-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 381418 INFO (zkCallback-3325-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 381418 INFO (zkCallback-3329-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 381418 INFO (zkCallback-3329-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 381422 INFO (zkCallback-3338-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 381423 INFO (zkCallback-3338-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 381431 INFO (qtp1137792167-5350) [n:127.0.0.1:61460_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=solrCloudCollectionConfig&newCollection=true&collection=people&version=2&replicaType=NRT&property.schema=schema-docValuesJoin.xml&property.config=solrconfig-doctransformers.xml&coreNodeName=core_node5&name=people_shard1_replica_n2&action=CREATE&numShards=2&shard=shard1&wt=javabin} status=0 QTime=1374 [junit4] 2> 381441 INFO (qtp1642831194-5337) [n:127.0.0.1:61456_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas [junit4] 2> 381531 INFO (zkCallback-3345-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 381531 INFO (zkCallback-3338-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 381531 INFO (zkCallback-3325-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 381531 INFO (zkCallback-3325-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 381531 INFO (zkCallback-3329-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 381531 INFO (zkCallback-3329-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 381532 INFO (zkCallback-3338-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/people/state.json] for collection [people] has occurred - updating... (live nodes size: [5]) [junit4] 2> 381534 INFO (qtp1642831194-5337) [n:127.0.0.1:61456_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={property.schema=schema-docValuesJoin.xml&property.config=solrconfig-doctransformers.xml&collection.configName=solrCloudCollectionConfig&name=people&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=1882 [junit4] 2> 381535 INFO (qtp1642831194-5344) [n:127.0.0.1:61456_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params property.schema=schema-minimal-with-another-uniqkey.xml&property.config=solrconfig-doctransformers.xml&collection.configName=solrCloudCollectionConfig&name=departments&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 381547 INFO (OverseerThreadFactory-3337-thread-2-processing-n:127.0.0.1:61456_solr) [n:127.0.0.1:61456_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection departments [j [...truncated too long message...] E1C4573D482B9]-worker-SendThread(127.0.0.1:61446)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100a114e1220000. [junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e1220000, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) [junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e1220000, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2] [junit4] 2> 384631 WARN (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker-SendThread(127.0.0.1:61446)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x100a114e1220001. [junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e1220001, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) [junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x100a114e1220001, likely server has closed socket [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2] [junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2] [junit4] 2> 384737 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> 7 /solr/collections/people/terms/shard1 [junit4] 2> 7 /solr/collections/people/terms/shard2 [junit4] 2> 7 /solr/collections/departments/terms/shard2 [junit4] 2> 7 /solr/collections/departments/terms/shard1 [junit4] 2> 6 /solr/clusterprops.json [junit4] 2> 6 /solr/aliases.json [junit4] 2> 5 /solr/packages.json [junit4] 2> 5 /solr/security.json [junit4] 2> 5 /solr/configs/solrCloudCollectionConfig [junit4] 2> 4 /solr/collections/departments/collectionprops.json [junit4] 2> 4 /solr/collections/people/collectionprops.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 40 /solr/collections/people/state.json [junit4] 2> 31 /solr/collections/departments/state.json [junit4] 2> 6 /solr/clusterstate.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 53 /solr/overseer/queue [junit4] 2> 25 /solr/live_nodes [junit4] 2> 18 /solr/collections [junit4] 2> 8 /solr/overseer/collection-queue-work [junit4] 2> [junit4] 2> 384754 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:61446 [junit4] 2> 384754 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:61446 [junit4] 2> 384754 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 61446 [junit4] 2> 384754 INFO (SUITE-TestSubQueryTransformerDistrib-seed#[7E1E1C4573D482B9]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released [junit4] 2> Oct 22, 2020 3:51:40 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> Oct 22, 2020 3:51:50 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.solr.response.transform.TestSubQueryTransformerDistrib: [junit4] 2> 1) Thread[id=5270, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestSubQueryTransformerDistrib] [junit4] 2> at [email protected]/java.lang.Thread.sleep(Native Method) [junit4] 2> at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:157) [junit4] 2> Oct 22, 2020 3:51:50 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: Starting to interrupt leaked threads: [junit4] 2> 1) Thread[id=5270, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestSubQueryTransformerDistrib] [junit4] 2> 395154 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:157) [zookeeper-3.6.2.jar:3.6.2] [junit4] 2> Oct 22, 2020 3:51:50 PM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll [junit4] 2> INFO: All leaked threads terminated. [junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {name_s=PostingsFormat(name=LuceneVarGapFixedInterval), dept_id_s=BlockTreeOrds(blocksize=128), _root_=Lucene84, title_s_dv=BlockTreeOrds(blocksize=128), notid=Lucene84, salary_i_dv=BlockTreeOrds(blocksize=128), _version_=Lucene84, dept_id_i=PostingsFormat(name=LuceneVarGapFixedInterval), name_s_dv=Lucene84, title_s=FST50, dept_ss_dv=PostingsFormat(name=LuceneVarGapFixedInterval), id=BlockTreeOrds(blocksize=128), text_t=Lucene84}, docValues:{id_dv=DocValuesFormat(name=Direct), dept_i_dv=DocValuesFormat(name=Direct), name_s_dv=DocValuesFormat(name=Direct), title_s_dv=DocValuesFormat(name=Asserting), dept_ss_dv=DocValuesFormat(name=Lucene80), dept_is_dv=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=1554, maxMBSortInHeap=6.347455438705106, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=xog, timezone=Australia/NSW [junit4] 2> NOTE: Mac OS X 10.14.6 x86_64/AdoptOpenJDK 11.0.6 (64-bit)/cpus=6,threads=1,free=181813536,total=406847488 [junit4] 2> NOTE: All tests run in this JVM: [UUIDFieldTest, AtomicUpdateRemovalJavabinTest, RequiredFieldsTest, TestCustomStream, TestUninvertingReader, TestRealTimeGet, SSLMigrationTest, TestSimpleTextCodec, TestSolrCLIRunExample, AutoAddReplicasIntegrationTest, CdcrVersionReplicationTest, TestNRTOpen, HdfsRestartWhileUpdatingTest, TestSha256AuthenticationProvider, JsonLoaderTest, AlternateDirectoryTest, TestRandomFaceting, CustomTermsComponentTest, ConcurrentCreateRoutedAliasTest, TestConfigSetsAPIZkFailure, OverseerModifyCollectionTest, TestInfoStreamLogging, SolrShardReporterTest, TriggerCooldownIntegrationTest, TestHttpShardHandlerFactory, TestTrieFacet, TestSchemalessBufferedUpdates, JWTAuthPluginIntegrationTest, TestDocTermOrdsUninvertLimit, ConfigureRecoveryStrategyTest, TestSubQueryTransformerDistrib] [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSubQueryTransformerDistrib -Dtests.seed=7E1E1C4573D482B9 -Dtests.slow=true -Dtests.locale=xog -Dtests.timezone=Australia/NSW -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] ERROR 0.00s J2 | TestSubQueryTransformerDistrib (suite) <<< [junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.response.transform.TestSubQueryTransformerDistrib: [junit4] > 1) Thread[id=5270, name=SessionTracker, state=TIMED_WAITING, group=TGRP-TestSubQueryTransformerDistrib] [junit4] > at [email protected]/java.lang.Thread.sleep(Native Method) [junit4] > at app//org.apache.zookeeper.server.SessionTrackerImpl.run(SessionTrackerImpl.java:157) [junit4] > at __randomizedtesting.SeedInfo.seed([7E1E1C4573D482B9]:0) [junit4] Completed [152/921 (1!)] on J2 in 18.47s, 1 test, 1 error <<< FAILURES! [...truncated 50306 lines...] [repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/1599/consoleText [repro] Revision: a1d614e18931712476998f6cfeded8c764abff0b [repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC" [repro] Repro line: ant test -Dtestcase=TestSubQueryTransformerDistrib -Dtests.seed=7E1E1C4573D482B9 -Dtests.slow=true -Dtests.locale=xog -Dtests.timezone=Australia/NSW -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] TestSubQueryTransformerDistrib [repro] ant compile-test [...truncated 3455 lines...] [repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestSubQueryTransformerDistrib" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=7E1E1C4573D482B9 -Dtests.slow=true -Dtests.locale=xog -Dtests.timezone=Australia/NSW -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [...truncated 81 lines...] [repro] Failures w/original seeds: [repro] 0/5 failed: org.apache.solr.response.transform.TestSubQueryTransformerDistrib [repro] Exiting with code 0 [...truncated 94 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
