Build: https://builds.apache.org/job/Lucene-Solr-repro/2620/
[...truncated 28 lines...] [repro] Jenkins log URL: https://builds.apache.org/job/Lucene-Solr-BadApples-NightlyTests-master/44/consoleText [repro] Revision: 669ca8f283bcb9dd62db0351ddfb131f04a56f64 [repro] Ant options: -Dtests.multiplier=2 -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-master/test-data/enwiki.random.lines.txt [repro] Repro line: ant test -Dtestcase=ForceLeaderTest -Dtests.method=testReplicasInLowerTerms -Dtests.seed=C1CE3DEC1B9E220D -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.badapples=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-master/test-data/enwiki.random.lines.txt -Dtests.locale=ar-SD -Dtests.timezone=Asia/Samarkand -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [repro] Repro line: ant test -Dtestcase=RestartWhileUpdatingTest -Dtests.seed=C1CE3DEC1B9E220D -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.badapples=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-master/test-data/enwiki.random.lines.txt -Dtests.locale=ar-TN -Dtests.timezone=Etc/GMT+9 -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [repro] Repro line: ant test -Dtestcase=TestCloudConsistency -Dtests.method=testOutOfSyncReplicasCannotBecomeLeader -Dtests.seed=C1CE3DEC1B9E220D -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.badapples=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-master/test-data/enwiki.random.lines.txt -Dtests.locale=vi-VN -Dtests.timezone=Europe/Kiev -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [repro] Repro line: ant test -Dtestcase=TriLevelCompositeIdRoutingTest -Dtests.method=test -Dtests.seed=C1CE3DEC1B9E220D -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.badapples=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-master/test-data/enwiki.random.lines.txt -Dtests.locale=es-US -Dtests.timezone=SystemV/MST7MDT -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [repro] Repro line: ant test -Dtestcase=TriLevelCompositeIdRoutingTest -Dtests.seed=C1CE3DEC1B9E220D -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.badapples=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-master/test-data/enwiki.random.lines.txt -Dtests.locale=es-US -Dtests.timezone=SystemV/MST7MDT -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [repro] git rev-parse --abbrev-ref HEAD [repro] git rev-parse HEAD [repro] Initial local git branch/revision: 000d0c1364b4c39d53095b37f263f75880d432db [repro] git fetch [repro] git checkout 669ca8f283bcb9dd62db0351ddfb131f04a56f64 [...truncated 2 lines...] [repro] git merge --ff-only [...truncated 1 lines...] [repro] ant clean [...truncated 6 lines...] [repro] Test suites by module: [repro] solr/core [repro] TriLevelCompositeIdRoutingTest [repro] TestCloudConsistency [repro] RestartWhileUpdatingTest [repro] ForceLeaderTest [repro] ant compile-test [...truncated 3592 lines...] [repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=20 -Dtests.class="*.TriLevelCompositeIdRoutingTest|*.TestCloudConsistency|*.RestartWhileUpdatingTest|*.ForceLeaderTest" -Dtests.showOutput=onerror -Dtests.multiplier=2 -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-master/test-data/enwiki.random.lines.txt -Dtests.seed=C1CE3DEC1B9E220D -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.badapples=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-BadApples-NightlyTests-master/test-data/enwiki.random.lines.txt -Dtests.locale=es-US -Dtests.timezone=SystemV/MST7MDT -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [...truncated 21617 lines...] [junit4] 2> 397216 ERROR (Finalizer) [ ] o.a.s.c.SolrCore REFCOUNT ERROR: unreferenced org.apache.solr.core.SolrCore@519af0e4 (collection1_shard1_replica_n3) has a reference count of -1 [junit4] 2> 397216 ERROR (Finalizer) [ ] o.a.s.c.SolrCore REFCOUNT ERROR: unreferenced org.apache.solr.core.SolrCore@25b3415f (collection1_shard1_replica_n5) has a reference count of -1 [junit4] 2> 397217 ERROR (Finalizer) [ ] o.a.s.c.SolrCore REFCOUNT ERROR: unreferenced org.apache.solr.core.SolrCore@7b8be7a4 (collection1_shard1_replica_n1) has a reference count of -1 [junit4] 2> 397217 ERROR (Finalizer) [ ] o.a.s.c.SolrCore REFCOUNT ERROR: unreferenced org.apache.solr.core.SolrCore@2867e7e5 (collection1_shard1_replica_n5) has a reference count of -1 [junit4] 2> 397217 ERROR (Finalizer) [ ] o.a.s.c.SolrCore REFCOUNT ERROR: unreferenced org.apache.solr.core.SolrCore@43626a6c (collection1_shard1_replica_n3) has a reference count of -1 [junit4] 2> 397217 ERROR (Finalizer) [ ] o.a.s.c.SolrCore REFCOUNT ERROR: unreferenced org.apache.solr.core.SolrCore@589c75ec (collection1_shard1_replica_n1) has a reference count of -1 [junit4] 2> 397218 ERROR (Finalizer) [ ] o.a.s.c.SolrCore REFCOUNT ERROR: unreferenced org.apache.solr.core.SolrCore@15a784d8 (control_collection_shard1_replica_n1) has a reference count of -1 [junit4] 2> 397239 INFO (qtp591638557-558) [n:127.0.0.1:38134_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:38134_&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 397244 INFO (OverseerThreadFactory-128-thread-1-processing-n:127.0.0.1:38134_) [n:127.0.0.1:38134_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection [junit4] 2> 397368 INFO (qtp591638557-559) [n:127.0.0.1:38134_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=2 [junit4] 2> 397376 INFO (qtp591638557-560) [n:127.0.0.1:38134_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 397382 INFO (qtp591638557-561) [n:127.0.0.1:38134_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 397384 INFO (qtp591638557-562) [n:127.0.0.1:38134_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 397387 INFO (qtp591638557-559) [n:127.0.0.1:38134_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CORE.coreName&wt=javabin&version=2&group=solr.node,solr.core} status=0 QTime=0 [junit4] 2> 397396 INFO (qtp591638557-560) [n:127.0.0.1:38134_ x:control_collection_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT [junit4] 2> 397397 INFO (qtp591638557-560) [n:127.0.0.1:38134_ x:control_collection_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 398436 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0 [junit4] 2> 398457 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test [junit4] 2> 398553 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id [junit4] 2> 398582 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from collection control_collection, trusted=true [junit4] 2> 398583 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995 [junit4] 2> 398595 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/control-001/cores/control_collection_shard1_replica_n1/data/] [junit4] 2> 398603 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@57a0226b [junit4] 2> 398617 WARN (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 398696 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 398696 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 398698 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 398698 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 398701 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=5, maxMergeAtOnceExplicit=9, maxMergedSegmentMB=29.8974609375, floorSegmentMB=0.734375, forceMergeDeletesPctAllowed=4.754733733843306, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2791787981264734, deletesPctAllowed=39.93590097416891 [junit4] 2> 398702 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@33ea6179[control_collection_shard1_replica_n1] main] [junit4] 2> 398703 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 398704 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 398705 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms. [junit4] 2> 398705 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1621813529250103296 [junit4] 2> 398710 INFO (searcherExecutor-133-thread-1-processing-n:127.0.0.1:38134_ x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher Searcher@33ea6179[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 398714 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, version=0} [junit4] 2> 398714 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/control_collection/leaders/shard1 [junit4] 2> 398718 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 398718 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 398718 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:38134/control_collection_shard1_replica_n1/ [junit4] 2> 398718 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 398719 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:38134/control_collection_shard1_replica_n1/ has no replicas [junit4] 2> 398719 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/72569868071075845-core_node2-n_0000000000 [junit4] 2> 398721 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:38134/control_collection_shard1_replica_n1/ shard1 [junit4] 2> 398823 INFO (zkCallback-190-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1]) [junit4] 2> 398823 INFO (zkCallback-190-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1]) [junit4] 2> 398825 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 398832 INFO (qtp591638557-560) [n:127.0.0.1:38134_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1435 [junit4] 2> 398837 INFO (qtp591638557-558) [n:127.0.0.1:38134_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas [junit4] 2> 398928 INFO (zkCallback-190-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1]) [junit4] 2> 398928 INFO (zkCallback-190-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1]) [junit4] 2> 398929 INFO (zkCallback-190-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1]) [junit4] 2> 398929 INFO (qtp591638557-558) [n:127.0.0.1:38134_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:38134_&wt=javabin&version=2} status=0 QTime=1690 [junit4] 2> 398933 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x101d1e917c60007, likely client has closed socket [junit4] 2> 398941 INFO (zkConnectionManagerCallback-209-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 398943 INFO (TEST-TriLevelCompositeIdRoutingTest.test-seed#[C1CE3DEC1B9E220D]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 398947 INFO (TEST-TriLevelCompositeIdRoutingTest.test-seed#[C1CE3DEC1B9E220D]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:40132/solr ready [junit4] 2> 398947 INFO (TEST-TriLevelCompositeIdRoutingTest.test-seed#[C1CE3DEC1B9E220D]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 398967 INFO (qtp591638557-558) [n:127.0.0.1:38134_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=12&createNodeSet=&stateFormat=2&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 398970 INFO (OverseerThreadFactory-128-thread-2-processing-n:127.0.0.1:38134_) [n:127.0.0.1:38134_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1 [junit4] 2> 398971 INFO (OverseerCollectionConfigSetProcessor-72569868071075845-127.0.0.1:38134_-n_0000000000) [n:127.0.0.1:38134_ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 399176 WARN (OverseerThreadFactory-128-thread-2-processing-n:127.0.0.1:38134_) [n:127.0.0.1:38134_ ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores. [junit4] 2> 399179 INFO (qtp591638557-558) [n:127.0.0.1:38134_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas [junit4] 2> 399180 INFO (qtp591638557-558) [n:127.0.0.1:38134_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=12&createNodeSet=&stateFormat=2&wt=javabin&version=2} status=0 QTime=213 [junit4] 2> 399182 INFO (TEST-TriLevelCompositeIdRoutingTest.test-seed#[C1CE3DEC1B9E220D]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=24 [junit4] 2> 399349 INFO (TEST-TriLevelCompositeIdRoutingTest.test-seed#[C1CE3DEC1B9E220D]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-1-001 of type NRT [junit4] 2> 399353 WARN (closeThreadPool-210-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 399354 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 399354 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 399354 INFO (closeThreadPool-210-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 399360 INFO (closeThreadPool-210-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 399360 INFO (closeThreadPool-210-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 399360 INFO (closeThreadPool-210-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 399360 INFO (closeThreadPool-210-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3476b2a9{/,null,AVAILABLE} [junit4] 2> 399361 WARN (closeThreadPool-210-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@21541063[provider=null,keyStore=null,trustStore=null] [junit4] 2> 399363 INFO (closeThreadPool-210-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6540d3e6{SSL,[ssl, http/1.1]}{127.0.0.1:34151} [junit4] 2> 399363 INFO (closeThreadPool-210-thread-1) [ ] o.e.j.s.Server Started @399439ms [junit4] 2> 399363 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=34151, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-1-001/cores} [junit4] 2> 399363 ERROR (closeThreadPool-210-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 399364 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 399364 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 399364 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 399364 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 399364 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-01-05T09:51:49.505Z [junit4] 2> 399367 INFO (zkConnectionManagerCallback-212-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 399369 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 399369 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-1-001/solr.xml [junit4] 2> 399377 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 399377 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 399391 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 399540 INFO (TEST-TriLevelCompositeIdRoutingTest.test-seed#[C1CE3DEC1B9E220D]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-2-001 of type NRT [junit4] 2> 399558 WARN (closeThreadPool-210-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 399559 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 399559 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 399559 INFO (closeThreadPool-210-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 399564 INFO (closeThreadPool-210-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 399564 INFO (closeThreadPool-210-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 399564 INFO (closeThreadPool-210-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 399565 INFO (closeThreadPool-210-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@13abf212{/,null,AVAILABLE} [junit4] 2> 399566 WARN (closeThreadPool-210-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@25483aa[provider=null,keyStore=null,trustStore=null] [junit4] 2> 399567 INFO (closeThreadPool-210-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@65ab69a0{SSL,[ssl, http/1.1]}{127.0.0.1:40270} [junit4] 2> 399567 INFO (closeThreadPool-210-thread-2) [ ] o.e.j.s.Server Started @399643ms [junit4] 2> 399567 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/tempDir-001/jetty2, solrconfig=solrconfig.xml, hostContext=/, hostPort=40270, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-2-001/cores} [junit4] 2> 399570 ERROR (closeThreadPool-210-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 399570 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 399570 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 399570 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 399571 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 399571 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-01-05T09:51:49.712Z [junit4] 2> 399579 INFO (zkConnectionManagerCallback-214-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 399586 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 399587 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-2-001/solr.xml [junit4] 2> 399599 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 399599 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 399601 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 399711 WARN (closeThreadPool-210-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 399714 WARN (closeThreadPool-210-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 399720 WARN (closeThreadPool-210-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@4fc98ab6[provider=null,keyStore=null,trustStore=null] [junit4] 2> 399729 WARN (closeThreadPool-210-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 399734 WARN (closeThreadPool-210-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@687681fb[provider=null,keyStore=null,trustStore=null] [junit4] 2> 399738 WARN (closeThreadPool-210-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 399740 WARN (closeThreadPool-210-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@72800edc[provider=null,keyStore=null,trustStore=null] [junit4] 2> 399741 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40132/solr [junit4] 2> 399746 WARN (closeThreadPool-210-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@3778d9b6[provider=null,keyStore=null,trustStore=null] [junit4] 2> 399747 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40132/solr [junit4] 2> 399753 INFO (zkConnectionManagerCallback-226-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 399753 INFO (zkConnectionManagerCallback-223-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 399762 INFO (TEST-TriLevelCompositeIdRoutingTest.test-seed#[C1CE3DEC1B9E220D]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-3-001 of type NRT [junit4] 2> 399767 WARN (closeThreadPool-210-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 399768 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 399768 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 399768 INFO (closeThreadPool-210-thread-3) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 399781 INFO (closeThreadPool-210-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 399781 INFO (closeThreadPool-210-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 399781 INFO (closeThreadPool-210-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 399782 INFO (closeThreadPool-210-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4f558ea2{/,null,AVAILABLE} [junit4] 2> 399782 WARN (closeThreadPool-210-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@50a13af5[provider=null,keyStore=null,trustStore=null] [junit4] 2> 399788 INFO (zkConnectionManagerCallback-230-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 399789 INFO (closeThreadPool-210-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3c94af36{SSL,[ssl, http/1.1]}{127.0.0.1:43222} [junit4] 2> 399789 INFO (closeThreadPool-210-thread-3) [ ] o.e.j.s.Server Started @399865ms [junit4] 2> 399789 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/tempDir-001/jetty3, solrconfig=solrconfig.xml, hostContext=/, hostPort=43222, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-3-001/cores} [junit4] 2> 399790 ERROR (closeThreadPool-210-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 399790 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 399790 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 399790 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 399790 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 399790 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-01-05T09:51:49.931Z [junit4] 2> 399791 INFO (zkConnectionManagerCallback-228-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 399811 INFO (zkConnectionManagerCallback-232-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 399814 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 399814 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-3-001/solr.xml [junit4] 2> 399837 INFO (closeThreadPool-210-thread-2) [n:127.0.0.1:40270_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 399838 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 399838 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 399839 INFO (closeThreadPool-210-thread-1) [n:127.0.0.1:34151_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 399840 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 399845 INFO (closeThreadPool-210-thread-2) [n:127.0.0.1:40270_ ] o.a.s.c.ZkController Publish node=127.0.0.1:40270_ as DOWN [junit4] 2> 399848 INFO (closeThreadPool-210-thread-2) [n:127.0.0.1:40270_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 399848 INFO (closeThreadPool-210-thread-2) [n:127.0.0.1:40270_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40270_ [junit4] 2> 399849 INFO (closeThreadPool-210-thread-1) [n:127.0.0.1:34151_ ] o.a.s.c.ZkController Publish node=127.0.0.1:34151_ as DOWN [junit4] 2> 399854 INFO (closeThreadPool-210-thread-1) [n:127.0.0.1:34151_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 399854 INFO (closeThreadPool-210-thread-1) [n:127.0.0.1:34151_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34151_ [junit4] 2> 399856 INFO (zkCallback-197-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 399864 INFO (zkCallback-190-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 399866 INFO (zkCallback-208-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 399869 INFO (zkCallback-197-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 399869 INFO (zkCallback-190-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 399871 INFO (zkCallback-229-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3) [junit4] 2> 399880 INFO (zkCallback-208-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 399891 INFO (zkCallback-227-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3) [junit4] 2> 399935 INFO (zkConnectionManagerCallback-241-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 399952 INFO (zkConnectionManagerCallback-246-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 399953 INFO (closeThreadPool-210-thread-1) [n:127.0.0.1:34151_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 399955 INFO (closeThreadPool-210-thread-1) [n:127.0.0.1:34151_ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:40132/solr ready [junit4] 2> 399955 INFO (closeThreadPool-210-thread-2) [n:127.0.0.1:40270_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 399957 INFO (closeThreadPool-210-thread-2) [n:127.0.0.1:40270_ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:40132/solr ready [junit4] 2> 399979 INFO (closeThreadPool-210-thread-1) [n:127.0.0.1:34151_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 400003 INFO (closeThreadPool-210-thread-2) [n:127.0.0.1:40270_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 400034 INFO (closeThreadPool-210-thread-1) [n:127.0.0.1:34151_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995 [junit4] 2> 400035 INFO (TEST-TriLevelCompositeIdRoutingTest.test-seed#[C1CE3DEC1B9E220D]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-4-001 of type NRT [junit4] 2> 400057 WARN (closeThreadPool-210-thread-4) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 400064 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 400064 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 400064 INFO (closeThreadPool-210-thread-4) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 400067 INFO (closeThreadPool-210-thread-1) [n:127.0.0.1:34151_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995 [junit4] 2> 400067 INFO (closeThreadPool-210-thread-1) [n:127.0.0.1:34151_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995 [junit4] 2> 400069 INFO (closeThreadPool-210-thread-2) [n:127.0.0.1:40270_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995 [junit4] 2> 400069 INFO (closeThreadPool-210-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 400069 INFO (closeThreadPool-210-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 400069 INFO (closeThreadPool-210-thread-4) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 400070 INFO (closeThreadPool-210-thread-1) [n:127.0.0.1:34151_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-1-001/cores [junit4] 2> 400076 INFO (closeThreadPool-210-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4281ad49{/,null,AVAILABLE} [junit4] 2> 400076 WARN (closeThreadPool-210-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@13a8b75b[provider=null,keyStore=null,trustStore=null] [junit4] 2> 400088 INFO (closeThreadPool-210-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@685a6d5c{SSL,[ssl, http/1.1]}{127.0.0.1:40231} [junit4] 2> 400088 INFO (closeThreadPool-210-thread-4) [ ] o.e.j.s.Server Started @400164ms [junit4] 2> 400088 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/tempDir-001/jetty4, solrconfig=solrconfig.xml, hostContext=/, hostPort=40231, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-4-001/cores} [junit4] 2> 400089 ERROR (closeThreadPool-210-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 400089 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 400089 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 400089 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 400089 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 400089 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-01-05T09:51:50.230Z [junit4] 2> 400117 INFO (zkConnectionManagerCallback-250-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 400124 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 400124 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-4-001/solr.xml [junit4] 2> 400140 INFO (closeThreadPool-210-thread-2) [n:127.0.0.1:40270_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995 [junit4] 2> 400140 INFO (closeThreadPool-210-thread-2) [n:127.0.0.1:40270_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995 [junit4] 2> 400143 INFO (closeThreadPool-210-thread-2) [n:127.0.0.1:40270_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-2-001/cores [junit4] 2> 400148 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 400148 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 400150 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 400254 WARN (closeThreadPool-210-thread-3) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 400286 WARN (closeThreadPool-210-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@3f19be1c[provider=null,keyStore=null,trustStore=null] [junit4] 2> 400293 WARN (closeThreadPool-210-thread-3) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 400328 WARN (closeThreadPool-210-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1f403f25[provider=null,keyStore=null,trustStore=null] [junit4] 2> 400329 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40132/solr [junit4] 2> 400333 INFO (zkConnectionManagerCallback-256-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 400347 INFO (TEST-TriLevelCompositeIdRoutingTest.test-seed#[C1CE3DEC1B9E220D]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 5 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-5-001 of type NRT [junit4] 2> 400349 WARN (closeThreadPool-210-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 400349 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 400349 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 400349 INFO (closeThreadPool-210-thread-2) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 400383 INFO (closeThreadPool-210-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 400383 INFO (closeThreadPool-210-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 400384 INFO (closeThreadPool-210-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 400391 INFO (closeThreadPool-210-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5249a8fc{/,null,AVAILABLE} [junit4] 2> 400397 WARN (closeThreadPool-210-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@736b0473[provider=null,keyStore=null,trustStore=null] [junit4] 2> 400409 INFO (zkConnectionManagerCallback-258-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 400409 INFO (closeThreadPool-210-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@184edb26{SSL,[ssl, http/1.1]}{127.0.0.1:36164} [junit4] 2> 400409 INFO (closeThreadPool-210-thread-2) [ ] o.e.j.s.Server Started @400485ms [junit4] 2> 400409 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/tempDir-001/jetty5, replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/, hostPort=36164, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-5-001/cores} [junit4] 2> 400410 ERROR (closeThreadPool-210-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 400410 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 400410 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 400410 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 400410 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 400410 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-01-05T09:51:50.551Z [junit4] 2> 400426 INFO (closeThreadPool-210-thread-3) [n:127.0.0.1:43222_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3) [junit4] 2> 400431 INFO (closeThreadPool-210-thread-3) [n:127.0.0.1:43222_ ] o.a.s.c.ZkController Publish node=127.0.0.1:43222_ as DOWN [junit4] 2> 400432 INFO (closeThreadPool-210-thread-3) [n:127.0.0.1:43222_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 400432 INFO (closeThreadPool-210-thread-3) [n:127.0.0.1:43222_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43222_ [junit4] 2> 400436 INFO (zkCallback-197-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 400436 INFO (zkCallback-208-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 400436 INFO (zkCallback-190-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 400445 INFO (zkCallback-229-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 400445 INFO (zkCallback-227-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 400446 INFO (zkConnectionManagerCallback-260-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 400449 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 400449 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-5-001/solr.xml [junit4] 2> 400457 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 400457 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 400458 INFO (zkCallback-240-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 400483 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 400487 INFO (zkCallback-245-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 400490 INFO (zkCallback-257-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4) [junit4] 2> 400524 INFO (zkConnectionManagerCallback-267-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 400526 INFO (closeThreadPool-210-thread-3) [n:127.0.0.1:43222_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 400528 INFO (closeThreadPool-210-thread-3) [n:127.0.0.1:43222_ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:40132/solr ready [junit4] 2> 400528 INFO (closeThreadPool-210-thread-3) [n:127.0.0.1:43222_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 400571 INFO (closeThreadPool-210-thread-3) [n:127.0.0.1:43222_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995 [junit4] 2> 400589 INFO (closeThreadPool-210-thread-3) [n:127.0.0.1:43222_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995 [junit4] 2> 400589 INFO (closeThreadPool-210-thread-3) [n:127.0.0.1:43222_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995 [junit4] 2> 400591 INFO (closeThreadPool-210-thread-3) [n:127.0.0.1:43222_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-3-001/cores [junit4] 2> 400617 WARN (closeThreadPool-210-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 400623 WARN (closeThreadPool-210-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@584cd581[provider=null,keyStore=null,trustStore=null] [junit4] 2> 400627 WARN (closeThreadPool-210-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 400633 WARN (closeThreadPool-210-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@730f0539[provider=null,keyStore=null,trustStore=null] [junit4] 2> 400634 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40132/solr [junit4] 2> 400656 INFO (zkConnectionManagerCallback-274-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 400686 INFO (TEST-TriLevelCompositeIdRoutingTest.test-seed#[C1CE3DEC1B9E220D]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 6 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-6-001 of type NRT [junit4] 2> 400687 WARN (closeThreadPool-210-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 400688 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 400688 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 400688 INFO (closeThreadPool-210-thread-1) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 400693 WARN (closeThreadPool-210-thread-4) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 400715 INFO (zkConnectionManagerCallback-276-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 400728 WARN (closeThreadPool-210-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1c39cf65[provider=null,keyStore=null,trustStore=null] [junit4] 2> 400730 INFO (closeThreadPool-210-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 400730 INFO (closeThreadPool-210-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 400730 INFO (closeThreadPool-210-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms [junit4] 2> 400730 INFO (closeThreadPool-210-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@63847f64{/,null,AVAILABLE} [junit4] 2> 400731 WARN (closeThreadPool-210-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@5fdc2d7b[provider=null,keyStore=null,trustStore=null] [junit4] 2> 400733 INFO (closeThreadPool-210-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@28cafdab{SSL,[ssl, http/1.1]}{127.0.0.1:34986} [junit4] 2> 400733 INFO (closeThreadPool-210-thread-1) [ ] o.e.j.s.Server Started @400809ms [junit4] 2> 400733 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/tempDir-001/jetty6, replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/, hostPort=34986, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-6-001/cores} [junit4] 2> 400733 ERROR (closeThreadPool-210-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 400733 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 400734 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 400734 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 400734 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 400734 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-01-05T09:51:50.875Z [junit4] 2> 400736 WARN (closeThreadPool-210-thread-4) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 400740 WARN (closeThreadPool-210-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@2379e3d4[provider=null,keyStore=null,trustStore=null] [junit4] 2> 400741 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4) [junit4] 2> 400741 INFO (zkConnectionManagerCallback-283-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 400741 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40132/solr [junit4] 2> 400748 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 400748 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-6-001/solr.xml [junit4] 2> 400751 INFO (zkConnectionManagerCallback-286-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 400752 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:36164_ as DOWN [junit4] 2> 400757 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 400757 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 400757 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 400757 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:36164_ [junit4] 2> 400760 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 400761 INFO (zkCallback-197-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 400762 INFO (zkCallback-190-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 400762 INFO (zkCallback-208-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 400762 INFO (zkCallback-227-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 400763 INFO (zkCallback-229-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 400769 INFO (zkCallback-245-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 400770 INFO (zkCallback-240-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 400770 INFO (zkCallback-257-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 400770 INFO (zkCallback-266-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 400770 INFO (zkCallback-275-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5) [junit4] 2> 400781 INFO (zkConnectionManagerCallback-288-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 400787 INFO (closeThreadPool-210-thread-4) [n:127.0.0.1:40231_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5) [junit4] 2> 400802 INFO (closeThreadPool-210-thread-4) [n:127.0.0.1:40231_ ] o.a.s.c.ZkController Publish node=127.0.0.1:40231_ as DOWN [junit4] 2> 400804 INFO (closeThreadPool-210-thread-4) [n:127.0.0.1:40231_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 400804 INFO (closeThreadPool-210-thread-4) [n:127.0.0.1:40231_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40231_ [junit4] 2> 400806 INFO (zkCallback-245-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 400806 INFO (zkCallback-266-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 400807 INFO (zkCallback-240-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 400807 INFO (zkCallback-190-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 400807 INFO (zkCallback-257-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 400807 INFO (zkCallback-229-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 400807 INFO (zkCallback-197-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 400807 INFO (zkCallback-208-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 400807 INFO (zkCallback-227-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 400820 INFO (zkCallback-287-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 400820 INFO (zkCallback-275-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6) [junit4] 2> 400832 WARN (closeThreadPool-210-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 400837 INFO (zkConnectionManagerCallback-295-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 400845 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (6) [junit4] 2> 400847 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:40132/solr ready [junit4] 2> 400847 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 400848 WARN (closeThreadPool-210-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@61b61ea2[provider=null,keyStore=null,trustStore=null] [junit4] 2> 400858 INFO (zkConnectionManagerCallback-302-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 400861 WARN (closeThreadPool-210-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 400864 INFO (closeThreadPool-210-thread-4) [n:127.0.0.1:40231_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (6) [junit4] 2> 400867 INFO (closeThreadPool-210-thread-4) [n:127.0.0.1:40231_ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:40132/solr ready [junit4] 2> 400885 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995 [junit4] 2> 400899 INFO (closeThreadPool-210-thread-4) [n:127.0.0.1:40231_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 400899 WARN (closeThreadPool-210-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@76502844[provider=null,keyStore=null,trustStore=null] [junit4] 2> 400900 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40132/solr [junit4] 2> 400901 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995 [junit4] 2> 400901 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995 [junit4] 2> 400907 INFO (closeThreadPool-210-thread-2) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-5-001/cores [junit4] 2> 400912 INFO (zkConnectionManagerCallback-308-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 400941 INFO (zkConnectionManagerCallback-310-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 400968 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (6) [junit4] 2> 400973 INFO (OverseerCollectionConfigSetProcessor-72569868071075845-127.0.0.1:38134_-n_0000000000) [n:127.0.0.1:38134_ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 400978 INFO (closeThreadPool-210-thread-4) [n:127.0.0.1:40231_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995 [junit4] 2> 400983 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:34986_ as DOWN [junit4] 2> 400984 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 400984 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34986_ [junit4] 2> 400987 INFO (zkCallback-245-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (7) [junit4] 2> 400987 INFO (zkCallback-240-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (7) [junit4] 2> 400987 INFO (zkCallback-197-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (7) [junit4] 2> 400988 INFO (zkCallback-190-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (7) [junit4] 2> 400988 INFO (zkCallback-208-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (7) [junit4] 2> 400988 INFO (zkCallback-266-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (7) [junit4] 2> 400989 INFO (zkCallback-227-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (7) [junit4] 2> 400989 INFO (zkCallback-275-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (7) [junit4] 2> 400989 INFO (zkCallback-229-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (7) [junit4] 2> 400989 INFO (zkCallback-257-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (7) [junit4] 2> 400990 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (7) [junit4] 2> 400990 INFO (TEST-TriLevelCompositeIdRoutingTest.test-seed#[C1CE3DEC1B9E220D]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 7 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-7-001 of type NRT [junit4] 2> 400991 WARN (closeThreadPool-210-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 400992 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 400992 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 400992 INFO (closeThreadPool-210-thread-3) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 401004 INFO (closeThreadPool-210-thread-4) [n:127.0.0.1:40231_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995 [junit4] 2> 401004 INFO (closeThreadPool-210-thread-4) [n:127.0.0.1:40231_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995 [junit4] 2> 401006 INFO (zkCallback-309-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (7) [junit4] 2> 401006 INFO (zkCallback-287-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (7) [junit4] 2> 401007 INFO (closeThreadPool-210-thread-4) [n:127.0.0.1:40231_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-4-001/cores [junit4] 2> 401020 INFO (zkCallback-294-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (7) [junit4] 2> 401029 INFO (closeThreadPool-210-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 401029 INFO (closeThreadPool-210-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 401029 INFO (closeThreadPool-210-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 401030 INFO (closeThreadPool-210-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1b9de51a{/,null,AVAILABLE} [junit4] 2> 401030 WARN (closeThreadPool-210-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@60c43625[provider=null,keyStore=null,trustStore=null] [junit4] 2> 401031 INFO (closeThreadPool-210-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@23cf4e34{SSL,[ssl, http/1.1]}{127.0.0.1:46791} [junit4] 2> 401032 INFO (closeThreadPool-210-thread-3) [ ] o.e.j.s.Server Started @401108ms [junit4] 2> 401032 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/tempDir-001/jetty7, replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/, hostPort=46791, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-7-001/cores} [junit4] 2> 401032 ERROR (closeThreadPool-210-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 401032 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 401032 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 401032 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 401033 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 401033 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-01-05T09:51:51.174Z [junit4] 2> 401044 INFO (zkConnectionManagerCallback-317-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 401056 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (7) [junit4] 2> 401058 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:40132/solr ready [junit4] 2> 401075 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory. [junit4] 2> 401083 INFO (zkConnectionManagerCallback-319-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 401088 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 401088 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-7-001/solr.xml [junit4] 2> 401104 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 401104 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 401106 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 401167 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995 [junit4] 2> 401208 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995 [junit4] 2> 401209 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995 [junit4] 2> 401211 INFO (closeThreadPool-210-thread-1) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-6-001/cores [junit4] 2> 401282 INFO (TEST-TriLevelCompositeIdRoutingTest.test-seed#[C1CE3DEC1B9E220D]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 8 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-8-001 of type NRT [junit4] 2> 401284 WARN (closeThreadPool-210-thread-4) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time [junit4] 2> 401285 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0) [junit4] 2> 401285 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ... [junit4] 2> 401285 INFO (closeThreadPool-210-thread-4) [ ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 1.8.0_191-b12 [junit4] 2> 401287 INFO (closeThreadPool-210-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0 [junit4] 2> 401287 INFO (closeThreadPool-210-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults [junit4] 2> 401287 INFO (closeThreadPool-210-thread-4) [ ] o.e.j.s.session node0 Scavenging every 660000ms [junit4] 2> 401288 INFO (closeThreadPool-210-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@24afe4bc{/,null,AVAILABLE} [junit4] 2> 401288 WARN (closeThreadPool-210-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6da7a452[provider=null,keyStore=null,trustStore=null] [junit4] 2> 401289 INFO (closeThreadPool-210-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@378ad223{SSL,[ssl, http/1.1]}{127.0.0.1:36057} [junit4] 2> 401289 INFO (closeThreadPool-210-thread-4) [ ] o.e.j.s.Server Started @401365ms [junit4] 2> 401289 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/tempDir-001/jetty8, replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/, hostPort=36057, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-8-001/cores} [junit4] 2> 401290 ERROR (closeThreadPool-210-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 401290 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory [junit4] 2> 401290 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.0.0 [junit4] 2> 401290 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 401291 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 401291 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-01-05T09:51:51.432Z [junit4] 2> 401312 INFO (zkConnectionManagerCallback-322-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 401315 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 401315 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/J0/temp/solr.cloud.TriLevelCompositeIdRoutingTest_C1CE3DEC1B9E220D-001/shard-8-001/solr.xml [junit4] 2> 401325 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored [junit4] 2> 401325 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored [junit4] 2> 401328 INFO (closeThreadPool-210-thread-4) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@194c6995, but no JMX reporters were configured - adding default JMX reporter. [junit4] 2> 401349 WARN (closeThreadPool-210-thread-3) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 401364 WARN (closeThreadPool-210-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@78392e91[provider=null,keyStore=null,trustStore=null] [junit4] 2> 401378 WARN (closeThreadPool-210-thread-3) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2 [junit4] 2> 401400 WARN (closeThreadPool-210-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@196343d3[provider=null,keyStore=null,trustStore=null] [junit4] 2> 401401 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40132/solr [junit4] 2> 401435 INFO (zkConnectionManagerCallback-328-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 401452 INFO (zkConnectionManagerCallback-330-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 401462 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (7) [junit4] 2> 401466 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:46791_ as DOWN [junit4] 2> 401467 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores [junit4] 2> 401467 INFO (closeThreadPool-210-thread-3) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46791_ [junit4] 2> 401469 INFO (zkCallback-208-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (7) -> (8) [junit4] 2> 401469 INFO (zkCallback-275-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (7) -> (8) [junit4] 2> 401469 INFO (zkCallback-229-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (7) -> (8) [junit4] 2> 401469 INFO (zkCallback-287-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (7) -> (8) [junit4] 2> 401470 INFO (zkCallback-301-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (7) -> (8) [junit4] 2> 401470 INFO (zkCallback-240-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (7) -> (8) [junit4] 2> 401470 INFO (zkCallback-227-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (7) -> (8) [junit4] 2> 401470 INFO (zkCallback-266-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (7) -> (8) [junit4] 2> 401470 INFO (zkCallback-294-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (7) -> (8) [junit4] 2> 401470 INFO (zkCallback-245-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (7) -> (8) [junit4] 2> 401470 INFO (zkCallback-190-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (7) -> (8) [junit4] 2> 401470 INFO (zkCallback-257-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (7) -> (8) [junit4] 2> 401470 INFO (zkCallback-197-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from Z [...truncated too long message...] s-slave/workspace/Lucene-Solr-repro/solr/server/lib/metrics-jetty9-3.2.6.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/server/lib/metrics-jvm-3.2.6.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/example/example-DIH/solr/db/lib/derby-10.9.1.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/example/example-DIH/solr/db/lib/hsqldb-2.4.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/lucene/build/core/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/lucene/test-framework/lib/hamcrest-core-1.3.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/lucene/test-framework/lib/junit-4.12.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/lucene/test-framework/lib/randomizedtesting-runner-2.7.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/antlr-2.7.7.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/antlr4-runtime-4.5.1-1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-core-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-core-api-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-core-avl-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-core-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-i18n-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-interceptor-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-interceptors-admin-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-interceptors-authn-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-interceptors-authz-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-interceptors-changelog-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/bcprov-jdk15on-1.60.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/byte-buddy-1.9.3.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/commons-collections-3.2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/hadoop-common-2.7.4-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/hadoop-hdfs-2.7.4-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/hadoop-minikdc-2.7.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/htrace-core-3.2.0-incubating.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/jetty-sslengine-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/mockito-core-2.23.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/netty-all-4.0.36.Final.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/core/test-lib/objenesis-2.6.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/lucene/build/analysis/icu/lucene-analyzers-icu-8.0.0-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/lucene/build/queryparser/classes/test:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/lucene/build/backward-codecs/classes/test:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/contrib/analysis-extras/lib/icu4j-62.1.jar:/usr/share/ant/lib/ant-launcher.jar:/usr/share/java/xmlParserAPIs.jar:/usr/share/java/xercesImpl.jar:/x1/jenkins/.ant/lib/ivy-2.4.0.jar:/usr/share/ant/lib/ant-junit.jar:/usr/share/ant/lib/ant-apache-log4j.jar:/usr/share/ant/lib/ant-junit4.jar:/usr/share/ant/lib/ant-javamail.jar:/usr/share/ant/lib/ant-apache-bsf.jar:/usr/share/ant/lib/ant-commons-net.jar:/usr/share/ant/lib/ant-antlr.jar:/usr/share/ant/lib/ant-jsch.jar:/usr/share/ant/lib/ant-apache-oro.jar:/usr/share/ant/lib/ant-commons-logging.jar:/usr/share/ant/lib/ant.jar:/usr/share/ant/lib/ant-testutil.jar:/usr/share/ant/lib/ant-jdepend.jar:/usr/share/ant/lib/ant-apache-bcel.jar:/usr/share/ant/lib/ant-apache-xalan2.jar:/usr/share/ant/lib/ant-apache-resolver.jar:/usr/share/ant/lib/ant-jmf.jar:/usr/share/ant/lib/ant-apache-regexp.jar:/usr/share/ant/lib/ant-swing.jar:/usr/local/asfpackages/java/jdk1.8.0_191/lib/tools.jar:/x1/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.7.2.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/temp/junit4-J2-20190105_094509_854213525460958926573.events @/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/solr/build/solr-core/test/temp/junit4-J2-20190105_094509_854261099187021313156.suites -stdin [junit4] ERROR: JVM J2 ended with an exception: Quit event not received from the forked process? This may indicate JVM crash or runner bugs. [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1563) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:123) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:997) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:994) [junit4] at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [junit4] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [junit4] at java.lang.Thread.run(Thread.java:748) BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/lucene/common-build.xml:1572: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-repro/lucene/common-build.xml:1099: At least one slave process threw an exception, first: Quit event not received from the forked process? This may indicate JVM crash or runner bugs. Total time: 17 minutes 28 seconds [repro] Setting last failure code to 256 [repro] Failures: [repro] 0/5 failed: org.apache.solr.cloud.ForceLeaderTest [repro] 0/5 failed: org.apache.solr.cloud.TestCloudConsistency [repro] 1/5 failed: org.apache.solr.cloud.TriLevelCompositeIdRoutingTest [repro] 2/5 failed: org.apache.solr.cloud.RestartWhileUpdatingTest [repro] git checkout 000d0c1364b4c39d53095b37f263f75880d432db Previous HEAD position was 669ca8f... SOLR-12514: Adding a positive testcase also HEAD is now at 000d0c1... SOLR-12514: FIxed the test and another bug [repro] Exiting with code 256 Archiving artifacts [Fast Archiver] No artifacts from Lucene-Solr-repro Repro-Lucene-Solr-Tests-7.x#1186 to compare, so performing full copy of artifacts Recording test results Build step 'Publish JUnit test result report' changed build result to UNSTABLE Email was triggered for: Unstable (Test Failures) Sending email for trigger: Unstable (Test Failures)
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
