You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/01/23 01:47:28 UTC
[JENKINS] Lucene-Solr-master-Linux (32bit/jdk1.8.0_144) - Build #
21323 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21323/
Java: 32bit/jdk1.8.0_144 -client -XX:+UseParallelGC
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.handler.component.DistributedQueryElevationComponentTest
Error Message:
1 thread leaked from SUITE scope at org.apache.solr.handler.component.DistributedQueryElevationComponentTest: 1) Thread[id=30405, name=qtp25014969-30405, state=TIMED_WAITING, group=TGRP-DistributedQueryElevationComponentTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.handler.component.DistributedQueryElevationComponentTest:
1) Thread[id=30405, name=qtp25014969-30405, state=TIMED_WAITING, group=TGRP-DistributedQueryElevationComponentTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([A1BFBAEAD7DCEF8B]:0)
FAILED: junit.framework.TestSuite.org.apache.solr.handler.component.DistributedQueryElevationComponentTest
Error Message:
There are still zombie threads that couldn't be terminated: 1) Thread[id=30405, name=qtp25014969-30405, state=TIMED_WAITING, group=TGRP-DistributedQueryElevationComponentTest] at sun.misc.Unsafe.park(Native Method) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308) at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708) at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626) at java.lang.Thread.run(Thread.java:748)
Stack Trace:
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
1) Thread[id=30405, name=qtp25014969-30405, state=TIMED_WAITING, group=TGRP-DistributedQueryElevationComponentTest]
at sun.misc.Unsafe.park(Native Method)
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
at java.lang.Thread.run(Thread.java:748)
at __randomizedtesting.SeedInfo.seed([A1BFBAEAD7DCEF8B]:0)
Build Log:
[...truncated 12909 lines...]
[junit4] Suite: org.apache.solr.handler.component.DistributedQueryElevationComponentTest
[junit4] 2> 1640763 INFO (SUITE-DistributedQueryElevationComponentTest-seed#[A1BFBAEAD7DCEF8B]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryElevationComponentTest_A1BFBAEAD7DCEF8B-001/init-core-data-001
[junit4] 2> 1640763 WARN (SUITE-DistributedQueryElevationComponentTest-seed#[A1BFBAEAD7DCEF8B]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=72 numCloses=72
[junit4] 2> 1640764 INFO (SUITE-DistributedQueryElevationComponentTest-seed#[A1BFBAEAD7DCEF8B]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1640766 INFO (SUITE-DistributedQueryElevationComponentTest-seed#[A1BFBAEAD7DCEF8B]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1640766 INFO (SUITE-DistributedQueryElevationComponentTest-seed#[A1BFBAEAD7DCEF8B]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 1640867 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryElevationComponentTest_A1BFBAEAD7DCEF8B-001/tempDir-001/control/cores/collection1
[junit4] 2> 1640887 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1640888 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1640888 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1640888 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 1640888 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@60cfe5{/,null,AVAILABLE}
[junit4] 2> 1640889 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1dcb335{SSL,[ssl, http/1.1]}{127.0.0.1:34357}
[junit4] 2> 1640890 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.Server Started @1642274ms
[junit4] 2> 1640890 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema11.xml, solrconfig=solrconfig-elevate.xml, hostContext=/, hostPort=34357, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryElevationComponentTest_A1BFBAEAD7DCEF8B-001/tempDir-001/control/cores}
[junit4] 2> 1640890 ERROR (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1640890 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 1640890 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1640890 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1640891 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-23T01:24:25.799Z
[junit4] 2> 1640891 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryElevationComponentTest_A1BFBAEAD7DCEF8B-001/tempDir-001/control/solr.xml
[junit4] 2> 1640897 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1640897 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1640898 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1e432f0, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1640936 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e432f0
[junit4] 2> 1640947 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e432f0
[junit4] 2> 1640947 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e432f0
[junit4] 2> 1640949 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryElevationComponentTest_A1BFBAEAD7DCEF8B-001/tempDir-001/control/cores
[junit4] 2> 1640949 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1640949 INFO (coreLoadExecutor-6157-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1640949 INFO (coreLoadExecutor-6157-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1640984 INFO (coreLoadExecutor-6157-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1640998 INFO (coreLoadExecutor-6157-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example
[junit4] 2> 1641058 INFO (coreLoadExecutor-6157-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.1 with uniqueid field id
[junit4] 2> 1641064 INFO (coreLoadExecutor-6157-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1641064 INFO (coreLoadExecutor-6157-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e432f0
[junit4] 2> 1641065 INFO (coreLoadExecutor-6157-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1641065 INFO (coreLoadExecutor-6157-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryElevationComponentTest_A1BFBAEAD7DCEF8B-001/tempDir-001/control/cores/collection1/data/]
[junit4] 2> 1641067 INFO (coreLoadExecutor-6157-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=47.8193359375, floorSegmentMB=0.46484375, forceMergeDeletesPctAllowed=25.012894679628925, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3075464754811522
[junit4] 2> 1641103 WARN (coreLoadExecutor-6157-thread-1) [ x:collection1] o.a.s.c.RequestHandlers no default request handler is registered (either '/select' or 'standard')
[junit4] 2> 1641104 INFO (coreLoadExecutor-6157-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1641104 INFO (coreLoadExecutor-6157-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1641105 INFO (coreLoadExecutor-6157-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=76.509765625, floorSegmentMB=1.2802734375, forceMergeDeletesPctAllowed=28.560530773199027, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3867382498846367
[junit4] 2> 1641105 INFO (coreLoadExecutor-6157-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b35e9f[collection1] main]
[junit4] 2> 1641105 WARN (coreLoadExecutor-6157-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 1641106 INFO (coreLoadExecutor-6157-thread-1) [ x:collection1] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/elevate.xml
[junit4] 2> 1641109 INFO (coreLoadExecutor-6157-thread-1) [ x:collection1] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/elevate.xml
[junit4] 2> 1641111 INFO (coreLoadExecutor-6157-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1641113 INFO (searcherExecutor-6158-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1b35e9f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1641184 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryElevationComponentTest_A1BFBAEAD7DCEF8B-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 1641186 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1641186 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1641186 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1641187 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 1641187 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1161674{/,null,AVAILABLE}
[junit4] 2> 1641188 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@a6ee99{SSL,[ssl, http/1.1]}{127.0.0.1:45017}
[junit4] 2> 1641188 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.Server Started @1642572ms
[junit4] 2> 1641188 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema11.xml, solrconfig=solrconfig-elevate.xml, hostContext=/, hostPort=45017, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryElevationComponentTest_A1BFBAEAD7DCEF8B-001/tempDir-001/shard0/cores}
[junit4] 2> 1641188 ERROR (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1641188 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 1641188 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1641188 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1641188 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-23T01:24:26.097Z
[junit4] 2> 1641189 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryElevationComponentTest_A1BFBAEAD7DCEF8B-001/tempDir-001/shard0/solr.xml
[junit4] 2> 1641192 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1641192 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1641193 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1e432f0, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1641224 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e432f0
[junit4] 2> 1641235 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e432f0
[junit4] 2> 1641235 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e432f0
[junit4] 2> 1641237 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryElevationComponentTest_A1BFBAEAD7DCEF8B-001/tempDir-001/shard0/cores
[junit4] 2> 1641237 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1641238 INFO (coreLoadExecutor-6167-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1641238 INFO (coreLoadExecutor-6167-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1641276 INFO (coreLoadExecutor-6167-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1641289 INFO (coreLoadExecutor-6167-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example
[junit4] 2> 1641336 INFO (coreLoadExecutor-6167-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.1 with uniqueid field id
[junit4] 2> 1641341 INFO (coreLoadExecutor-6167-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1641341 INFO (coreLoadExecutor-6167-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e432f0
[junit4] 2> 1641341 INFO (coreLoadExecutor-6167-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1641341 INFO (coreLoadExecutor-6167-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryElevationComponentTest_A1BFBAEAD7DCEF8B-001/tempDir-001/shard0/cores/collection1/data/]
[junit4] 2> 1641344 INFO (coreLoadExecutor-6167-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=47.8193359375, floorSegmentMB=0.46484375, forceMergeDeletesPctAllowed=25.012894679628925, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3075464754811522
[junit4] 2> 1641402 WARN (coreLoadExecutor-6167-thread-1) [ x:collection1] o.a.s.c.RequestHandlers no default request handler is registered (either '/select' or 'standard')
[junit4] 2> 1641402 INFO (coreLoadExecutor-6167-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1641403 INFO (coreLoadExecutor-6167-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1641404 INFO (coreLoadExecutor-6167-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=76.509765625, floorSegmentMB=1.2802734375, forceMergeDeletesPctAllowed=28.560530773199027, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3867382498846367
[junit4] 2> 1641405 INFO (coreLoadExecutor-6167-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@e3e348[collection1] main]
[junit4] 2> 1641405 WARN (coreLoadExecutor-6167-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 1641406 INFO (coreLoadExecutor-6167-thread-1) [ x:collection1] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/elevate.xml
[junit4] 2> 1641411 INFO (coreLoadExecutor-6167-thread-1) [ x:collection1] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/elevate.xml
[junit4] 2> 1641415 INFO (coreLoadExecutor-6167-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1641416 INFO (searcherExecutor-6168-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@e3e348[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1641531 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryElevationComponentTest_A1BFBAEAD7DCEF8B-001/tempDir-001/shard1/cores/collection1
[junit4] 2> 1641534 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1641535 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1641535 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1641535 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 1641536 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f9d7fa{/,null,AVAILABLE}
[junit4] 2> 1641537 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@d1fda{SSL,[ssl, http/1.1]}{127.0.0.1:33021}
[junit4] 2> 1641537 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.Server Started @1642921ms
[junit4] 2> 1641537 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema11.xml, solrconfig=solrconfig-elevate.xml, hostContext=/, hostPort=33021, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryElevationComponentTest_A1BFBAEAD7DCEF8B-001/tempDir-001/shard1/cores}
[junit4] 2> 1641537 ERROR (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1641537 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 1641537 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1641537 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1641538 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-23T01:24:26.446Z
[junit4] 2> 1641538 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryElevationComponentTest_A1BFBAEAD7DCEF8B-001/tempDir-001/shard1/solr.xml
[junit4] 2> 1641549 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1641549 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1641550 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1e432f0, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1641582 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e432f0
[junit4] 2> 1641593 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e432f0
[junit4] 2> 1641594 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e432f0
[junit4] 2> 1641596 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryElevationComponentTest_A1BFBAEAD7DCEF8B-001/tempDir-001/shard1/cores
[junit4] 2> 1641596 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1641596 INFO (coreLoadExecutor-6177-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1641596 INFO (coreLoadExecutor-6177-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1641631 INFO (coreLoadExecutor-6177-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1641645 INFO (coreLoadExecutor-6177-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example
[junit4] 2> 1641717 INFO (coreLoadExecutor-6177-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.1 with uniqueid field id
[junit4] 2> 1641722 INFO (coreLoadExecutor-6177-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1641723 INFO (coreLoadExecutor-6177-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e432f0
[junit4] 2> 1641723 INFO (coreLoadExecutor-6177-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1641723 INFO (coreLoadExecutor-6177-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryElevationComponentTest_A1BFBAEAD7DCEF8B-001/tempDir-001/shard1/cores/collection1/data/]
[junit4] 2> 1641726 INFO (coreLoadExecutor-6177-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=47.8193359375, floorSegmentMB=0.46484375, forceMergeDeletesPctAllowed=25.012894679628925, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3075464754811522
[junit4] 2> 1641783 WARN (coreLoadExecutor-6177-thread-1) [ x:collection1] o.a.s.c.RequestHandlers no default request handler is registered (either '/select' or 'standard')
[junit4] 2> 1641783 INFO (coreLoadExecutor-6177-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1641783 INFO (coreLoadExecutor-6177-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1641785 INFO (coreLoadExecutor-6177-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=76.509765625, floorSegmentMB=1.2802734375, forceMergeDeletesPctAllowed=28.560530773199027, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3867382498846367
[junit4] 2> 1641785 INFO (coreLoadExecutor-6177-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@187c37a[collection1] main]
[junit4] 2> 1641786 WARN (coreLoadExecutor-6177-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 1641787 INFO (coreLoadExecutor-6177-thread-1) [ x:collection1] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/elevate.xml
[junit4] 2> 1641792 INFO (coreLoadExecutor-6177-thread-1) [ x:collection1] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/elevate.xml
[junit4] 2> 1641795 INFO (coreLoadExecutor-6177-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1641797 INFO (searcherExecutor-6178-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@187c37a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1641884 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryElevationComponentTest_A1BFBAEAD7DCEF8B-001/tempDir-001/shard2/cores/collection1
[junit4] 2> 1641886 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 1641887 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1641887 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1641887 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 1641887 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@9a036c{/,null,AVAILABLE}
[junit4] 2> 1641888 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@da65ff{SSL,[ssl, http/1.1]}{127.0.0.1:35377}
[junit4] 2> 1641888 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.Server Started @1643272ms
[junit4] 2> 1641888 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema11.xml, solrconfig=solrconfig-elevate.xml, hostContext=/, hostPort=35377, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryElevationComponentTest_A1BFBAEAD7DCEF8B-001/tempDir-001/shard2/cores}
[junit4] 2> 1641888 ERROR (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1641888 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.0.0
[junit4] 2> 1641889 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 1641889 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 1641889 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-01-23T01:24:26.798Z
[junit4] 2> 1641889 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryElevationComponentTest_A1BFBAEAD7DCEF8B-001/tempDir-001/shard2/solr.xml
[junit4] 2> 1641892 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 1641892 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 1641893 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1e432f0, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 1641924 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e432f0
[junit4] 2> 1641935 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e432f0
[junit4] 2> 1641935 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e432f0
[junit4] 2> 1641937 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryElevationComponentTest_A1BFBAEAD7DCEF8B-001/tempDir-001/shard2/cores
[junit4] 2> 1641937 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 1641937 INFO (coreLoadExecutor-6187-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 1641938 INFO (coreLoadExecutor-6187-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 1641971 INFO (coreLoadExecutor-6187-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 1641985 INFO (coreLoadExecutor-6187-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example
[junit4] 2> 1642031 INFO (coreLoadExecutor-6187-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.1 with uniqueid field id
[junit4] 2> 1642034 INFO (coreLoadExecutor-6187-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 1642035 INFO (coreLoadExecutor-6187-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1e432f0
[junit4] 2> 1642035 INFO (coreLoadExecutor-6187-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 1642035 INFO (coreLoadExecutor-6187-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.DistributedQueryElevationComponentTest_A1BFBAEAD7DCEF8B-001/tempDir-001/shard2/cores/collection1/data/]
[junit4] 2> 1642037 INFO (coreLoadExecutor-6187-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=10, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=47.8193359375, floorSegmentMB=0.46484375, forceMergeDeletesPctAllowed=25.012894679628925, segmentsPerTier=36.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3075464754811522
[junit4] 2> 1642073 WARN (coreLoadExecutor-6187-thread-1) [ x:collection1] o.a.s.c.RequestHandlers no default request handler is registered (either '/select' or 'standard')
[junit4] 2> 1642074 INFO (coreLoadExecutor-6187-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1642074 INFO (coreLoadExecutor-6187-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1642075 INFO (coreLoadExecutor-6187-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=37, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=76.509765625, floorSegmentMB=1.2802734375, forceMergeDeletesPctAllowed=28.560530773199027, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3867382498846367
[junit4] 2> 1642075 INFO (coreLoadExecutor-6187-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@13f10be[collection1] main]
[junit4] 2> 1642075 WARN (coreLoadExecutor-6187-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 1642076 INFO (coreLoadExecutor-6187-thread-1) [ x:collection1] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/elevate.xml
[junit4] 2> 1642080 INFO (coreLoadExecutor-6187-thread-1) [ x:collection1] o.a.s.h.c.QueryElevationComponent Loading QueryElevation from: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf/elevate.xml
[junit4] 2> 1642082 INFO (coreLoadExecutor-6187-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1642084 INFO (searcherExecutor-6188-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@13f10be[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1642084 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 1642150 INFO (qtp32813291-30388) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1642150 INFO (qtp32813291-30388) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 1642206 INFO (qtp25014969-30407) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1642207 INFO (qtp25014969-30407) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 1642269 INFO (qtp29015509-30424) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1642270 INFO (qtp29015509-30424) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 1642334 INFO (qtp28970945-30439) [ x:collection1] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 1642334 INFO (qtp28970945-30439) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{deleteByQuery=*:*} 0 0
[junit4] 2> 1642339 INFO (qtp32813291-30390) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1]} 0 2
[junit4] 2> 1642342 INFO (qtp25014969-30405) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[1]} 0 1
[junit4] 2> 1642345 INFO (qtp32813291-30388) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2]} 0 1
[junit4] 2> 1642349 INFO (qtp29015509-30422) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[2]} 0 1
[junit4] 2> 1642351 INFO (qtp32813291-30390) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3]} 0 0
[junit4] 2> 1642355 INFO (qtp28970945-30441) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[3]} 0 1
[junit4] 2> 1642357 INFO (qtp32813291-30388) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4]} 0 0
[junit4] 2> 1642360 INFO (qtp25014969-30407) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[4]} 0 1
[junit4] 2> 1642362 INFO (qtp32813291-30390) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5]} 0 0
[junit4] 2> 1642365 INFO (qtp29015509-30424) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[5]} 0 1
[junit4] 2> 1642368 INFO (qtp32813291-30388) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6]} 0 0
[junit4] 2> 1642370 INFO (qtp28970945-30439) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[6]} 0 1
[junit4] 2> 1642373 INFO (qtp32813291-30390) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7]} 0 0
[junit4] 2> 1642375 INFO (qtp28970945-30441) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={wt=javabin&version=2}{add=[7]} 0 0
[junit4] 2> 1642377 INFO (qtp32813291-30388) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1642377 INFO (qtp32813291-30388) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@182967e commitCommandVersion:0
[junit4] 2> 1642382 INFO (qtp32813291-30388) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1636449[collection1] main]
[junit4] 2> 1642383 INFO (qtp32813291-30388) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1642388 INFO (searcherExecutor-6158-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1636449[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c7)))}
[junit4] 2> 1642388 INFO (qtp32813291-30388) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 11
[junit4] 2> 1642390 INFO (qtp25014969-30405) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1642390 INFO (qtp25014969-30405) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1444966 commitCommandVersion:0
[junit4] 2> 1642394 INFO (qtp25014969-30405) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@574fd2[collection1] main]
[junit4] 2> 1642394 INFO (qtp25014969-30405) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1642399 INFO (searcherExecutor-6168-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@574fd2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c2)))}
[junit4] 2> 1642399 INFO (qtp25014969-30405) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 9
[junit4] 2> 1642401 INFO (qtp29015509-30422) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1642401 INFO (qtp29015509-30422) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@1cab46e commitCommandVersion:0
[junit4] 2> 1642405 INFO (qtp29015509-30422) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@1400f8[collection1] main]
[junit4] 2> 1642405 INFO (qtp29015509-30422) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1642433 INFO (searcherExecutor-6178-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1400f8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c2)))}
[junit4] 2> 1642433 INFO (qtp29015509-30422) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 32
[junit4] 2> 1642435 INFO (qtp28970945-30439) [ x:collection1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 1642435 INFO (qtp28970945-30439) [ x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@d0c685 commitCommandVersion:0
[junit4] 2> 1642438 INFO (qtp28970945-30439) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@12dee5f[collection1] main]
[junit4] 2> 1642439 INFO (qtp28970945-30439) [ x:collection1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 1642444 INFO (searcherExecutor-6188-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@12dee5f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c3)))}
[junit4] 2> 1642445 INFO (qtp28970945-30439) [ x:collection1] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 9
[junit4] 2> 1642448 INFO (qtp32813291-30390) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={q=*:*&distrib=false&qt=/elevate&fl=id,+score,+[elevated]&sort=id+desc&rows=500&wt=javabin&version=2&shards.qt=/elevate} hits=7 status=0 QTime=1
[junit4] 2> 1642463 INFO (qtp25014969-30406) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=id+desc&shard.url=https://127.0.0.1:45017//collection1&rows=500&version=2&shards.qt=/elevate&q=*:*&NOW=1516670667360&isShard=true&wt=javabin} hits=2 status=0 QTime=0
[junit4] 2> 1642476 INFO (qtp29015509-30422) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=id+desc&shard.url=[ff01::114]:33332/|https://127.0.0.1:33021//collection1&rows=500&version=2&shards.qt=/elevate&q=*:*&NOW=1516670667360&isShard=true&wt=javabin} hits=2 status=0 QTime=0
[junit4] 2> 1642479 INFO (qtp28970945-30439) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id&fl=score&shards.purpose=4&start=0&fsv=true&sort=id+desc&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|https://127.0.0.1:35377//collection1|[ff01::083]:33332/&rows=500&version=2&shards.qt=/elevate&q=*:*&NOW=1516670667360&isShard=true&wt=javabin} hits=3 status=0 QTime=0
[junit4] 2> 1642483 INFO (qtp29015509-30423) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id,+score,+[elevated]&shards.purpose=64&shard.url=[ff01::114]:33332/|https://127.0.0.1:33021//collection1&rows=500&version=2&shards.qt=/elevate&q=*:*&NOW=1516670667360&ids=2,5&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1642484 INFO (qtp28970945-30440) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id,+score,+[elevated]&shards.purpose=64&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|https://127.0.0.1:35377//collection1|[ff01::083]:33332/&rows=500&version=2&shards.qt=/elevate&q=*:*&NOW=1516670667360&ids=3,6,7&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1642484 INFO (qtp25014969-30401) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id,+score,+[elevated]&shards.purpose=64&shard.url=https://127.0.0.1:45017//collection1&rows=500&version=2&shards.qt=/elevate&q=*:*&NOW=1516670667360&ids=1,4&isShard=true&wt=javabin} status=0 QTime=1
[junit4] 2> 1642485 INFO (qtp25014969-30407) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={q=*:*&shards=https://127.0.0.1:45017//collection1,[ff01::114]:33332/|https://127.0.0.1:33021//collection1,[ff01::114]:33332/|[ff01::213]:33332/|https://127.0.0.1:35377//collection1|[ff01::083]:33332/&qt=/elevate&fl=id,+score,+[elevated]&sort=id+desc&rows=500&wt=javabin&version=2&shards.qt=/elevate} hits=7 status=0 QTime=34
[junit4] 2> 1642488 INFO (qtp32813291-30388) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={q=ZZZZ&forceElevation=true&distrib=false&qt=/elevate&fl=*,+[elevated]&sort=int_i+desc&rows=500&wt=javabin&version=2&shards.qt=/elevate} hits=5 status=0 QTime=1
[junit4] 2> 1642534 INFO (qtp28970945-30435) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id&shards.purpose=4&start=0&fsv=true&sort=int_i+desc&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|https://127.0.0.1:35377//collection1|[ff01::213]:33332/&rows=500&version=2&shards.qt=/elevate&q=ZZZZ&forceElevation=true&NOW=1516670667399&isShard=true&wt=javabin} hits=2 status=0 QTime=2
[junit4] 2> 1642535 INFO (qtp25014969-30401) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id&shards.purpose=4&start=0&fsv=true&sort=int_i+desc&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/|https://127.0.0.1:45017//collection1&rows=500&version=2&shards.qt=/elevate&q=ZZZZ&forceElevation=true&NOW=1516670667399&isShard=true&wt=javabin} hits=1 status=0 QTime=2
[junit4] 2> 1642536 INFO (qtp29015509-30423) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id&shards.purpose=4&start=0&fsv=true&sort=int_i+desc&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|https://127.0.0.1:33021//collection1|[ff01::114]:33332/&rows=500&version=2&shards.qt=/elevate&q=ZZZZ&forceElevation=true&NOW=1516670667399&isShard=true&wt=javabin} hits=2 status=0 QTime=1
[junit4] 2> 1642539 INFO (qtp28970945-30436) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=*,+[elevated]&shards.purpose=64&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|https://127.0.0.1:35377//collection1|[ff01::213]:33332/&rows=500&version=2&shards.qt=/elevate&q=ZZZZ&forceElevation=true&NOW=1516670667399&ids=3,6&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1642539 INFO (qtp29015509-30418) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=*,+[elevated]&shards.purpose=64&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|https://127.0.0.1:33021//collection1|[ff01::114]:33332/&rows=500&version=2&shards.qt=/elevate&q=ZZZZ&forceElevation=true&NOW=1516670667399&ids=2,5&isShard=true&wt=javabin} status=0 QTime=1
[junit4] 2> 1642541 INFO (qtp25014969-30407) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=*,+[elevated]&shards.purpose=64&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/|https://127.0.0.1:45017//collection1&rows=500&version=2&shards.qt=/elevate&q=ZZZZ&forceElevation=true&NOW=1516670667399&ids=1&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1642542 INFO (qtp28970945-30441) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={q=ZZZZ&forceElevation=true&shards=[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/|https://127.0.0.1:45017//collection1,[ff01::083]:33332/|[ff01::213]:33332/|https://127.0.0.1:33021//collection1|[ff01::114]:33332/,[ff01::114]:33332/|[ff01::083]:33332/|https://127.0.0.1:35377//collection1|[ff01::213]:33332/&qt=/elevate&fl=*,+[elevated]&sort=int_i+desc&rows=500&wt=javabin&version=2&shards.qt=/elevate} hits=5 status=0 QTime=51
[junit4] 2> 1642544 INFO (qtp32813291-30390) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={q=solr&forceElevation=true&distrib=false&qt=/elevate&fl=*,+[elevated]&sort=int_i+asc&rows=500&wt=javabin&version=2&shards.qt=/elevate} hits=1 status=0 QTime=0
[junit4] 2> 1642548 INFO (qtp25014969-30401) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id&shards.purpose=4&start=0&fsv=true&sort=int_i+asc&shard.url=https://127.0.0.1:45017//collection1|[ff01::083]:33332/|[ff01::114]:33332/&rows=500&version=2&shards.qt=/elevate&q=solr&forceElevation=true&NOW=1516670667455&isShard=true&wt=javabin} hits=0 status=0 QTime=0
[junit4] 2> 1642550 INFO (qtp29015509-30423) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id&shards.purpose=4&start=0&fsv=true&sort=int_i+asc&shard.url=https://127.0.0.1:33021//collection1&rows=500&version=2&shards.qt=/elevate&q=solr&forceElevation=true&NOW=1516670667455&isShard=true&wt=javabin} hits=0 status=0 QTime=0
[junit4] 2> 1642551 INFO (qtp28970945-30435) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id&shards.purpose=4&start=0&fsv=true&sort=int_i+asc&shard.url=[ff01::114]:33332/|https://127.0.0.1:35377//collection1&rows=500&version=2&shards.qt=/elevate&q=solr&forceElevation=true&NOW=1516670667455&isShard=true&wt=javabin} hits=1 status=0 QTime=0
[junit4] 2> 1642554 INFO (qtp28970945-30436) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=*,+[elevated]&shards.purpose=64&shard.url=[ff01::114]:33332/|https://127.0.0.1:35377//collection1&rows=500&version=2&shards.qt=/elevate&q=solr&forceElevation=true&NOW=1516670667455&ids=7&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1642555 INFO (qtp28970945-30440) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={q=solr&forceElevation=true&shards=https://127.0.0.1:45017//collection1|[ff01::083]:33332/|[ff01::114]:33332/,https://127.0.0.1:33021//collection1,[ff01::114]:33332/|https://127.0.0.1:35377//collection1&qt=/elevate&fl=*,+[elevated]&sort=int_i+asc&rows=500&wt=javabin&version=2&shards.qt=/elevate} hits=1 status=0 QTime=9
[junit4] 2> 1642557 INFO (qtp32813291-30388) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={q=ZZZZ&forceElevation=true&distrib=false&qt=/elevate&fl=*,+[elevated]&sort=id+desc&rows=500&wt=javabin&version=2&shards.qt=/elevate} hits=5 status=0 QTime=1
[junit4] 2> 1642563 INFO (qtp28970945-30439) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id&shards.purpose=4&start=0&fsv=true&sort=id+desc&shard.url=https://127.0.0.1:35377//collection1&rows=500&version=2&shards.qt=/elevate&q=ZZZZ&forceElevation=true&NOW=1516670667468&isShard=true&wt=javabin} hits=2 status=0 QTime=1
[junit4] 2> 1642565 INFO (qtp29015509-30422) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id&shards.purpose=4&start=0&fsv=true&sort=id+desc&shard.url=https://127.0.0.1:33021//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=500&version=2&shards.qt=/elevate&q=ZZZZ&forceElevation=true&NOW=1516670667468&isShard=true&wt=javabin} hits=2 status=0 QTime=1
[junit4] 2> 1642570 INFO (qtp25014969-30406) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id&shards.purpose=4&start=0&fsv=true&sort=id+desc&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|https://127.0.0.1:45017//collection1&rows=500&version=2&shards.qt=/elevate&q=ZZZZ&forceElevation=true&NOW=1516670667468&isShard=true&wt=javabin} hits=1 status=0 QTime=1
[junit4] 2> 1642572 INFO (qtp25014969-30458) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=*,+[elevated]&shards.purpose=64&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|https://127.0.0.1:45017//collection1&rows=500&version=2&shards.qt=/elevate&q=ZZZZ&forceElevation=true&NOW=1516670667468&ids=1&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1642574 INFO (qtp28970945-30440) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=*,+[elevated]&shards.purpose=64&shard.url=https://127.0.0.1:35377//collection1&rows=500&version=2&shards.qt=/elevate&q=ZZZZ&forceElevation=true&NOW=1516670667468&ids=3,6&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1642575 INFO (qtp29015509-30423) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=*,+[elevated]&shards.purpose=64&shard.url=https://127.0.0.1:33021//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=500&version=2&shards.qt=/elevate&q=ZZZZ&forceElevation=true&NOW=1516670667468&ids=2,5&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1642576 INFO (qtp25014969-30405) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={q=ZZZZ&forceElevation=true&shards=[ff01::083]:33332/|[ff01::213]:33332/|https://127.0.0.1:45017//collection1,https://127.0.0.1:33021//collection1|[ff01::114]:33332/|[ff01::213]:33332/,https://127.0.0.1:35377//collection1&qt=/elevate&fl=*,+[elevated]&sort=id+desc&rows=500&wt=javabin&version=2&shards.qt=/elevate} hits=5 status=0 QTime=16
[junit4] 2> 1642579 INFO (qtp32813291-30390) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={q=XXXX&forceElevation=true&enableElevation=true&distrib=false&qt=/elevate&fl=id,+[elevated]&elevateIds=6&sort=id+desc&rows=500&wt=javabin&version=2&shards.qt=/elevate} hits=3 status=0 QTime=0
[junit4] 2> 1642602 INFO (qtp28970945-30440) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id&shards.purpose=4&start=0&fsv=true&sort=id+desc&shard.url=https://127.0.0.1:35377//collection1&rows=500&version=2&shards.qt=/elevate&q=XXXX&forceElevation=true&enableElevation=true&NOW=1516670667490&elevateIds=6&isShard=true&wt=javabin} hits=1 status=0 QTime=1
[junit4] 2> 1642604 INFO (qtp25014969-30405) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id&shards.purpose=4&start=0&fsv=true&sort=id+desc&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|https://127.0.0.1:45017//collection1&rows=500&version=2&shards.qt=/elevate&q=XXXX&forceElevation=true&enableElevation=true&NOW=1516670667490&elevateIds=6&isShard=true&wt=javabin} hits=2 status=0 QTime=0
[junit4] 2> 1642604 INFO (qtp29015509-30417) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id&shards.purpose=4&start=0&fsv=true&sort=id+desc&shard.url=[ff01::114]:33332/|https://127.0.0.1:33021//collection1|[ff01::083]:33332/|[ff01::213]:33332/&rows=500&version=2&shards.qt=/elevate&q=XXXX&forceElevation=true&enableElevation=true&NOW=1516670667490&elevateIds=6&isShard=true&wt=javabin} hits=0 status=0 QTime=1
[junit4] 2> 1642607 INFO (qtp28970945-30436) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id,+[elevated]&shards.purpose=64&shard.url=https://127.0.0.1:35377//collection1&rows=500&version=2&shards.qt=/elevate&q=XXXX&forceElevation=true&enableElevation=true&NOW=1516670667490&elevateIds=6&ids=6&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1642607 INFO (qtp25014969-30458) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id,+[elevated]&shards.purpose=64&shard.url=[ff01::114]:33332/|[ff01::213]:33332/|https://127.0.0.1:45017//collection1&rows=500&version=2&shards.qt=/elevate&q=XXXX&forceElevation=true&enableElevation=true&NOW=1516670667490&elevateIds=6&ids=1,4&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1642608 INFO (qtp29015509-30424) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={q=XXXX&forceElevation=true&shards=[ff01::114]:33332/|[ff01::213]:33332/|https://127.0.0.1:45017//collection1,[ff01::114]:33332/|https://127.0.0.1:33021//collection1|[ff01::083]:33332/|[ff01::213]:33332/,https://127.0.0.1:35377//collection1&enableElevation=true&qt=/elevate&fl=id,+[elevated]&elevateIds=6&sort=id+desc&rows=500&wt=javabin&version=2&shards.qt=/elevate} hits=3 status=0 QTime=27
[junit4] 2> 1642616 INFO (qtp25014969-30406) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id&shards.purpose=4&start=0&fsv=true&sort=id+desc&shard.url=[ff01::083]:33332/|https://127.0.0.1:45017//collection1|[ff01::213]:33332/|[ff01::114]:33332/&rows=500&version=2&shards.qt=/elevate&q=XXXX&forceElevation=true&enableElevation=true&NOW=1516670667522&elevateIds=6&elevateIds=wt&elevateIds=javabin&isShard=true&wt=javabin} hits=2 status=0 QTime=0
[junit4] 2> 1642617 INFO (qtp28970945-30439) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id&shards.purpose=4&start=0&fsv=true&sort=id+desc&shard.url=[ff01::213]:33332/|https://127.0.0.1:35377//collection1|[ff01::114]:33332/&rows=500&version=2&shards.qt=/elevate&q=XXXX&forceElevation=true&enableElevation=true&NOW=1516670667522&elevateIds=6&elevateIds=wt&elevateIds=javabin&isShard=true&wt=javabin} hits=1 status=0 QTime=0
[junit4] 2> 1642618 INFO (qtp29015509-30422) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id&shards.purpose=4&start=0&fsv=true&sort=id+desc&shard.url=https://127.0.0.1:33021//collection1&rows=500&version=2&shards.qt=/elevate&q=XXXX&forceElevation=true&enableElevation=true&NOW=1516670667522&elevateIds=6&elevateIds=wt&elevateIds=javabin&isShard=true&wt=javabin} hits=0 status=0 QTime=0
[junit4] 2> 1642620 INFO (qtp28970945-30436) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id,[elevated]&shards.purpose=64&shard.url=[ff01::213]:33332/|https://127.0.0.1:35377//collection1|[ff01::114]:33332/&rows=500&version=2&shards.qt=/elevate&q=XXXX&forceElevation=true&enableElevation=true&NOW=1516670667522&elevateIds=6&elevateIds=wt&elevateIds=javabin&ids=6&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1642620 INFO (qtp25014969-30459) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={df=text&distrib=false&qt=/elevate&fl=id,[elevated]&shards.purpose=64&shard.url=[ff01::083]:33332/|https://127.0.0.1:45017//collection1|[ff01::213]:33332/|[ff01::114]:33332/&rows=500&version=2&shards.qt=/elevate&q=XXXX&forceElevation=true&enableElevation=true&NOW=1516670667522&elevateIds=6&elevateIds=wt&elevateIds=javabin&ids=1,4&isShard=true&wt=javabin} status=0 QTime=0
[junit4] 2> 1642621 INFO (qtp25014969-30401) [ x:collection1] o.a.s.c.S.Request [collection1] webapp= path=/elevate params={q=XXXX&forceElevation=true&shards=[ff01::083]:33332/|https://127.0.0.1:45017//collection1|[ff01::213]:33332/|[ff01::114]:33332/,https://127.0.0.1:33021//collection1,[ff01::213]:33332/|https://127.0.0.1:35377//collection1|[ff01::114]:33332/&enableElevation=true&qt=/elevate&fl=id,[elevated]&elevateIds=6&elevateIds=wt&elevateIds=javabin&sort=id+desc&rows=500&wt=javabin&version=2&shards.qt=/elevate} hits=3 status=0 QTime=7
[junit4] 2> 1642622 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.SolrTestCaseJ4 ###Ending test
[junit4] 2> 1642623 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1dcb335{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 1642623 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1101777
[junit4] 2> 1642623 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1642623 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1b5a7ac: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@7ad831
[junit4] 2> 1642630 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1642630 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@138bce2: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@e96077
[junit4] 2> 1642636 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1642636 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5b7857: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@14b351c
[junit4] 2> 1642637 INFO (coreCloseExecutor-6192-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1dfc865
[junit4] 2> 1642637 INFO (coreCloseExecutor-6192-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=31443045
[junit4] 2> 1642637 INFO (coreCloseExecutor-6192-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@c3a3f0: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@1f5e020
[junit4] 2> 1642644 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@60cfe5{/,null,UNAVAILABLE}
[junit4] 2> 1642644 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1642646 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@a6ee99{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 1642646 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=18210290
[junit4] 2> 1642646 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1642647 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@9532bb: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@15d1b0a
[junit4] 2> 1642653 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1642653 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1660db8: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@e96077
[junit4] 2> 1642659 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1642659 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@14aa0d1: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@14b351c
[junit4] 2> 1642660 INFO (coreCloseExecutor-6194-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1988df9
[junit4] 2> 1642660 INFO (coreCloseExecutor-6194-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=26775033
[junit4] 2> 1642660 INFO (coreCloseExecutor-6194-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1c098ec: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2f818e
[junit4] 2> 1642668 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1161674{/,null,UNAVAILABLE}
[junit4] 2> 1642668 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1672667 WARN (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.u.t.QueuedThreadPool QueuedThreadPool@qtp25014969{STOPPING,8<=9<=10000,i=0,q=1} Couldn't stop Thread[qtp25014969-30405,5,TGRP-DistributedQueryElevationComponentTest]
[junit4] 2> 1672668 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@d1fda{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 1672668 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=13737997
[junit4] 2> 1672669 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1672669 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6d3609: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@4ab6fb
[junit4] 2> 1672676 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1672677 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1185a80: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@e96077
[junit4] 2> 1672682 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1672683 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@e1950a: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@14b351c
[junit4] 2> 1672683 INFO (coreCloseExecutor-6196-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@bc02a2
[junit4] 2> 1672683 INFO (coreCloseExecutor-6196-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=12321442
[junit4] 2> 1672683 INFO (coreCloseExecutor-6196-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1d9a11: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@14f0f73
[junit4] 2> 1672691 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1f9d7fa{/,null,UNAVAILABLE}
[junit4] 2> 1672691 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1672693 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@da65ff{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 1672693 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=5877329
[junit4] 2> 1672693 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 1672693 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@36bece: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@8ecc06
[junit4] 2> 1672702 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 1672702 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@ac28ef: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@e96077
[junit4] 2> 1672711 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 1672712 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@13f4804: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@14b351c
[junit4] 2> 1672712 INFO (coreCloseExecutor-6198-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@f452d1
[junit4] 2> 1672712 INFO (coreCloseExecutor-6198-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=16011985
[junit4] 2> 1672712 INFO (coreCloseExecutor-6198-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@13829ec: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@bda886
[junit4] 2> 1672723 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@9a036c{/,null,UNAVAILABLE}
[junit4] 2> 1672723 INFO (TEST-DistributedQueryElevationComponentTest.test-seed#[A1BFBAEAD7DCEF8B]) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 1672724 INFO (SUITE-DistributedQueryElevationComponentTest-seed#[A1BFBAEAD7DCEF8B]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> Jan 23, 2018 1:24:57 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> Jan 23, 2018 1:25:17 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> SEVERE: 1 thread leaked from SUITE scope at org.apache.solr.handler.component.DistributedQueryElevationComponentTest:
[junit4] 2> 1) Thread[id=30405, name=qtp25014969-30405, state=TIMED_WAITING, group=TGRP-DistributedQueryElevationComponentTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Jan 23, 2018 1:25:17 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> INFO: Starting to interrupt leaked threads:
[junit4] 2> 1) Thread[id=30405, name=qtp25014969-30405, state=TIMED_WAITING, group=TGRP-DistributedQueryElevationComponentTest]
[junit4] 2> Jan 23, 2018 1:25:20 AM com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
[junit4] 2> SEVERE: There are still zombie threads that couldn't be terminated:
[junit4] 2> 1) Thread[id=30405, name=qtp25014969-30405, state=TIMED_WAITING, group=TGRP-DistributedQueryElevationComponentTest]
[junit4] 2> at sun.misc.Unsafe.park(Native Method)
[junit4] 2> at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {field_t=PostingsFormat(name=Memory), id=PostingsFormat(name=Memory), text=Lucene50(blocksize=128), plow_t=Lucene50(blocksize=128)}, docValues:{int_i=DocValuesFormat(name=Lucene70), id_i=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=237, maxMBSortInHeap=7.87228968361232, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1711df), locale=sr-BA, timezone=America/Mexico_City
[junit4] 2> NOTE: Linux 4.13.0-26-generic i386/Oracle Corporation 1.8.0_144 (32-bit)/cpus=8,threads=2,free=144499592,total=508821504
[junit4] 2> NOTE: All tests run in this JVM: [TestFastLRUCache, DocExpirationUpdateProcessorFactoryTest, BadComponentTest, CdcrBidirectionalTest, TestSolrFieldCacheBean, QueryEqualityTest, TestCustomDocTransformer, TestPointFields, PrimitiveFieldTypeTest, TestNumericRangeQuery32, DistributedFacetExistsSmallTest, PreAnalyzedUpdateProcessorTest, TestLeaderInitiatedRecoveryThread, AutoScalingHandlerTest, TestHashQParserPlugin, AssignTest, RuleEngineTest, TestPartialUpdateDeduplication, RemoteQueryErrorTest, DirectUpdateHandlerOptimizeTest, TestZkChroot, SuggestComponentTest, OverseerTaskQueueTest, TestLegacyFieldCache, TestReversedWildcardFilterFactory, MoveReplicaTest, TestCloudSchemaless, SignatureUpdateProcessorFactoryTest, PeerSyncTest, OverseerModifyCollectionTest, TestConfigReload, HdfsChaosMonkeySafeLeaderTest, SolrMetricsIntegrationTest, RequestLoggingTest, TestFieldCache, TestClassicSimilarityFactory, SolrCloudExampleTest, SolrIndexMetricsTest, TestExclusionRuleCollectionAccess, HLLSerializationTest, TestSkipOverseerOperations, TestFieldCacheSortRandom, TestNodeAddedTrigger, DistributedVersionInfoTest, IndexSchemaTest, PrimUtilsTest, ConnectionManagerTest, SampleTest, TestNamedUpdateProcessors, SimplePostToolTest, SuggesterTest, TestRTGBase, PKIAuthenticationIntegrationTest, SimpleCollectionCreateDeleteTest, CheckHdfsIndexTest, AsyncCallRequestStatusResponseTest, CdcrUpdateLogTest, TestFieldCollectionResource, TestManagedSynonymGraphFilterFactory, TestPullReplicaErrorHandling, BlockJoinFacetSimpleTest, LeaderInitiatedRecoveryOnShardRestartTest, ScriptEngineTest, SegmentsInfoRequestHandlerTest, TestSQLHandler, TestFieldTypeResource, ChaosMonkeySafeLeaderTest, FastVectorHighlighterTest, CoreAdminRequestStatusTest, TestConfigSetsAPIExclusivity, ShufflingReplicaListTransformerTest, ShardRoutingCustomTest, TestStressLiveNodes, DistribDocExpirationUpdateProcessorTest, TestCollectionAPIs, CSVRequestHandlerTest, HdfsBasicDistributedZk2Test, TestPostingsSolrHighlighter, SolrCoreMetricManagerTest, TestDistributedGrouping, TestNoOpRegenerator, SolrCloudReportersTest, TestCursorMarkWithoutUniqueKey, TestSimDistributedQueue, TestFuzzyAnalyzedSuggestions, TestPerFieldSimilarityWithDefaultOverride, V2StandaloneTest, TestSlowCompositeReaderWrapper, TestSolr4Spatial, DistributedFacetSimpleRefinementLongTailTest, BasicZkTest, SpellCheckCollatorWithCollapseTest, UUIDUpdateProcessorFallbackTest, TestOnReconnectListenerSupport, MetricUtilsTest, DocValuesMissingTest, DirectSolrConnectionTest, SortSpecParsingTest, UpdateRequestProcessorFactoryTest, TestSubQueryTransformerCrossCore, CoreSorterTest, TestLRUStatsCache, TestSolrIndexConfig, FileUtilsTest, BasicFunctionalityTest, DeleteShardTest, TestSimpleTrackingShardHandler, DOMUtilTest, HdfsDirectoryTest, TestRuleBasedAuthorizationPlugin, TestReplicationHandler, SyncSliceTest, QueryElevationComponentTest, SolrJmxReporterCloudTest, TestComplexPhraseLeadingWildcard, TestSolrCloudWithSecureImpersonation, TestRawResponseWriter, BitVectorTest, SaslZkACLProviderTest, TestInitParams, JSONWriterTest, JsonLoaderTest, CdcrReplicationHandlerTest, TestHdfsUpdateLog, DocumentBuilderTest, BasicDistributedZk2Test, UnloadDistributedZkTest, OpenCloseCoreStressTest, OverseerTest, LeaderElectionIntegrationTest, ZkSolrClientTest, TestFaceting, TestRecovery, TestHashPartitioner, ZkControllerTest, TestStressReorder, TestReload, TestStressVersions, DistributedTermsComponentTest, TestGroupingSearch, TestFunctionQuery, TestBadConfig, HighlighterTest, DistributedQueryElevationComponentTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=DistributedQueryElevationComponentTest -Dtests.seed=A1BFBAEAD7DCEF8B -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr-BA -Dtests.timezone=America/Mexico_City -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 0.00s J1 | DistributedQueryElevationComponentTest (suite) <<<
[junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE scope at org.apache.solr.handler.component.DistributedQueryElevationComponentTest:
[junit4] > 1) Thread[id=30405, name=qtp25014969-30405, state=TIMED_WAITING, group=TGRP-DistributedQueryElevationComponentTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at __randomizedtesting.SeedInfo.seed([A1BFBAEAD7DCEF8B]:0)Throwable #2: com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie threads that couldn't be terminated:
[junit4] > 1) Thread[id=30405, name=qtp25014969-30405, state=TIMED_WAITING, group=TGRP-DistributedQueryElevationComponentTest]
[junit4] > at sun.misc.Unsafe.park(Native Method)
[junit4] > at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
[junit4] > at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2163)
[junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.reservedWait(ReservedThreadExecutor.java:308)
[junit4] > at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:373)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:708)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:626)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > at __randomizedtesting.SeedInfo.seed([A1BFBAEAD7DCEF8B]:0)
[junit4] Completed [335/768 (1!)] on J1 in 55.26s, 1 test, 2 errors <<< FAILURES!
[...truncated 45206 lines...]