Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-MacOSX/406/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseG1GC

2 tests failed.
FAILED:  
junit.framework.TestSuite.org.apache.solr.ltr.feature.TestUserTermScoreWithQ

Error Message:
1 thread leaked from SUITE scope at 
org.apache.solr.ltr.feature.TestUserTermScoreWithQ:     1) Thread[id=89, 
name=qtp531507010-89, state=TIMED_WAITING, group=TGRP-TestUserTermScoreWithQ]   
      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.ltr.feature.TestUserTermScoreWithQ: 
   1) Thread[id=89, name=qtp531507010-89, state=TIMED_WAITING, 
group=TGRP-TestUserTermScoreWithQ]
        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([DFE82B7B342E5B03]:0)


FAILED:  
junit.framework.TestSuite.org.apache.solr.ltr.feature.TestUserTermScoreWithQ

Error Message:
There are still zombie threads that couldn't be terminated:    1) Thread[id=89, 
name=qtp531507010-89, state=TIMED_WAITING, group=TGRP-TestUserTermScoreWithQ]   
      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=89, name=qtp531507010-89, state=TIMED_WAITING, 
group=TGRP-TestUserTermScoreWithQ]
        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([DFE82B7B342E5B03]:0)




Build Log:
[...truncated 21610 lines...]
   [junit4] Suite: org.apache.solr.ltr.feature.TestUserTermScoreWithQ
   [junit4]   2> Creating dataDir: 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestUserTermScoreWithQ_DFE82B7B342E5B03-001/init-core-data-001
   [junit4]   2> 23955 WARN  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3
   [junit4]   2> 23955 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 23960 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN) 
w/ MAC_OS_X supressed clientAuth
   [junit4]   2> 24557 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 24558 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 24636 WARN  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.c.SolrConfig Couldn't add files from 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/contrib/ltr/src/test-files/solr/models
 filtered by null to classpath: 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/contrib/ltr/src/test-files/solr/models
   [junit4]   2> 24641 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
   [junit4]   2> 24685 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=example
   [junit4]   2> 24804 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid field id
   [junit4]   2> 25210 WARN  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via 
JMX.
   [junit4]   2> 25210 WARN  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via 
JMX.
   [junit4]   2> 25210 WARN  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via 
JMX.
   [junit4]   2> 25212 INFO  (coreLoadExecutor-58-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 25255 WARN  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.c.SolrConfig Couldn't add files from 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/contrib/ltr/src/test-files/solr/models
 filtered by null to classpath: 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/contrib/ltr/src/test-files/solr/models
   [junit4]   2> 25260 INFO  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
   [junit4]   2> 25280 INFO  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.s.IndexSchema [collection1] Schema name=example
   [junit4]   2> 25307 INFO  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid field id
   [junit4]   2> 25313 INFO  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/contrib/ltr/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 25314 WARN  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.m.r.SolrJmxReporter No JMX server found. Not exposing Solr metrics via 
JMX.
   [junit4]   2> 25314 INFO  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 25315 INFO  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/contrib/ltr/src/test-files/solr/collection1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestUserTermScoreWithQ_DFE82B7B342E5B03-001/init-core-data-001/]
   [junit4]   2> 25681 WARN  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.c.RequestHandlers no default request handler is registered (either 
'/select' or 'standard')
   [junit4]   2> 25683 INFO  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 25683 INFO  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 25693 INFO  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms; 
   [junit4]   2> 25693 INFO  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.u.CommitTracker Soft AutoCommit: if uncommited for 1000ms; 
   [junit4]   2> 25730 INFO  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@786a86f4[collection1] main]
   [junit4]   2> 25731 WARN  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/contrib/ltr/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 25731 INFO  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.r.RestManager Registered ManagedResource impl 
org.apache.solr.ltr.store.rest.ManagedFeatureStore for path 
/schema/feature-store
   [junit4]   2> 25731 WARN  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.r.ManagedResource No stored data found for /schema/feature-store
   [junit4]   2> 25731 INFO  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.l.s.r.ManagedFeatureStore ------ managed feature ~ loading ------
   [junit4]   2> 25731 INFO  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.r.ManagedResource Notified 1 observers of /schema/feature-store
   [junit4]   2> 25731 INFO  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.r.RestManager Registered new managed resource /schema/feature-store
   [junit4]   2> 25732 INFO  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.r.RestManager Registered ManagedResource impl 
org.apache.solr.ltr.store.rest.ManagedModelStore for path /schema/model-store
   [junit4]   2> 25732 WARN  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.r.ManagedResource No stored data found for /schema/model-store
   [junit4]   2> 25732 INFO  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.l.s.r.ManagedModelStore INIT model store
   [junit4]   2> 25732 INFO  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.l.s.r.ManagedModelStore ------ managed models ~ loading ------
   [junit4]   2> 25732 INFO  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.r.ManagedResource Notified 1 observers of /schema/model-store
   [junit4]   2> 25732 INFO  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.r.RestManager Registered new managed resource /schema/model-store
   [junit4]   2> 25732 INFO  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 25734 INFO  
(searcherExecutor-59-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@786a86f4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 25734 INFO  (coreLoadExecutor-58-thread-1) [    x:collection1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1589913400727568384
   [junit4]   2> 25742 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 25762 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 25762 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 25764 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestUserTermScoreWithQ_DFE82B7B342E5B03-001/tempDir-003/cores/core
   [junit4]   2> 25804 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 
2017-11-21T11:27:37-10:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 25807 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 25808 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 25808 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 25809 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@5709853c{/solr,null,AVAILABLE}
   [junit4]   2> 25849 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.e.j.s.AbstractConnector Started ServerConnector@5713ccab{SSL,[ssl, 
http/1.1]}{127.0.0.1:64958}
   [junit4]   2> 25849 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.e.j.s.Server Started @29693ms
   [junit4]   2> 25849 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, 
hostPort=64958, 
configSetBaseDir=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestUserTermScoreWithQ_DFE82B7B342E5B03-001/tempDir-001,
 
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestUserTermScoreWithQ_DFE82B7B342E5B03-001/tempDir-003/cores}
   [junit4]   2> 25850 ERROR 
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 25858 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
7.3.0
   [junit4]   2> 25858 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 25858 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 25859 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-01-18T07:12:55.709Z
   [junit4]   2> 25859 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestUserTermScoreWithQ_DFE82B7B342E5B03-001/tempDir-001/solr.xml
   [junit4]   2> 26149 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestUserTermScoreWithQ_DFE82B7B342E5B03-001/tempDir-003/cores
   [junit4]   2> 26150 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 26153 INFO  (coreLoadExecutor-69-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 26189 WARN  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.c.SolrConfig Couldn't add files from 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestUserTermScoreWithQ_DFE82B7B342E5B03-001/tempDir-001/models
 filtered by null to classpath: 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestUserTermScoreWithQ_DFE82B7B342E5B03-001/tempDir-001/models
   [junit4]   2> 26194 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
   [junit4]   2> 26214 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.s.IndexSchema [collection1] Schema name=example
   [junit4]   2> 26265 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.s.IndexSchema Loaded schema example/1.5 with uniqueid field id
   [junit4]   2> 26280 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
configset 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestUserTermScoreWithQ_DFE82B7B342E5B03-001/tempDir-001/collection1,
 trusted=true
   [junit4]   2> 26281 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 26281 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestUserTermScoreWithQ_DFE82B7B342E5B03-001/tempDir-001/collection1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestUserTermScoreWithQ_DFE82B7B342E5B03-001/tempDir-003/cores/core/data/]
   [junit4]   2> 26492 WARN  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.c.RequestHandlers no default request handler is registered (either 
'/select' or 'standard')
   [junit4]   2> 26494 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 26494 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 26497 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.u.CommitTracker Hard AutoCommit: if uncommited for 15000ms; 
   [junit4]   2> 26497 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.u.CommitTracker Soft AutoCommit: if uncommited for 1000ms; 
   [junit4]   2> 26539 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@57cd6a03[collection1] main]
   [junit4]   2> 26540 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: 
/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestUserTermScoreWithQ_DFE82B7B342E5B03-001/tempDir-001/collection1/conf
   [junit4]   2> 26541 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.r.RestManager Registered ManagedResource impl 
org.apache.solr.ltr.store.rest.ManagedFeatureStore for path 
/schema/feature-store
   [junit4]   2> 26543 WARN  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.r.ManagedResource No stored data found for /schema/feature-store
   [junit4]   2> 26543 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.l.s.r.ManagedFeatureStore ------ managed feature ~ loading ------
   [junit4]   2> 26543 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.r.ManagedResource Notified 1 observers of /schema/feature-store
   [junit4]   2> 26543 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.r.RestManager Registered new managed resource /schema/feature-store
   [junit4]   2> 26543 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.r.RestManager Registered ManagedResource impl 
org.apache.solr.ltr.store.rest.ManagedModelStore for path /schema/model-store
   [junit4]   2> 26543 WARN  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.r.ManagedResource No stored data found for /schema/model-store
   [junit4]   2> 26544 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.l.s.r.ManagedModelStore INIT model store
   [junit4]   2> 26544 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.l.s.r.ManagedModelStore ------ managed models ~ loading ------
   [junit4]   2> 26544 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.r.ManagedResource Notified 1 observers of /schema/model-store
   [junit4]   2> 26544 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.r.RestManager Registered new managed resource /schema/model-store
   [junit4]   2> 26544 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 26544 INFO  (coreLoadExecutor-69-thread-1) [    x:collection1] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1589913401576914944
   [junit4]   2> 26545 INFO  
(searcherExecutor-70-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@57cd6a03[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 26547 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.SolrJettyTestBase Jetty Assigned Port#64958
   [junit4]   2> 26938 INFO  (qtp531507010-87) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={stream.body=<add><doc><field+name%3D"id">1</field><field+name%3D"title">w1</field><field+name%3D"description">w1</field><field+name%3D"popularity">1</field></doc></add>}{add=[1
 (1589913401952305152)]} 0 37
   [junit4]   2> 26955 INFO  (qtp531507010-89) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={stream.body=<add><doc><field+name%3D"id">2</field><field+name%3D"title">w2+2asd+asdd+didid</field><field+name%3D"description">w2+2asd+asdd+didid</field><field+name%3D"popularity">2</field></doc></add>}{add=[2
 (1589913402001588224)]} 0 6
   [junit4]   2> 26963 INFO  (qtp531507010-87) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={stream.body=<add><doc><field+name%3D"id">3</field><field+name%3D"title">w3</field><field+name%3D"description">w3</field><field+name%3D"popularity">3</field></doc></add>}{add=[3
 (1589913402015219712)]} 0 2
   [junit4]   2> 26970 INFO  (qtp531507010-89) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={stream.body=<add><doc><field+name%3D"id">4</field><field+name%3D"title">w4</field><field+name%3D"description">w4</field><field+name%3D"popularity">4</field></doc></add>}{add=[4
 (1589913402022559744)]} 0 1
   [junit4]   2> 26973 INFO  (qtp531507010-87) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={stream.body=<add><doc><field+name%3D"id">5</field><field+name%3D"title">w5</field><field+name%3D"description">w5</field><field+name%3D"popularity">5</field></doc></add>}{add=[5
 (1589913402026754048)]} 0 0
   [junit4]   2> 26984 INFO  (qtp531507010-89) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={stream.body=<add><doc><field+name%3D"id">6</field><field+name%3D"title">w1+w2</field><field+name%3D"description">w1+w2</field><field+name%3D"popularity">6</field></doc></add>}{add=[6
 (1589913402037239808)]} 0 1
   [junit4]   2> 27000 INFO  (qtp531507010-87) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={stream.body=<add><doc><field+name%3D"id">7</field><field+name%3D"title">w1+w2+w3+w4+w5</field><field+name%3D"description">w1+w2+w3+w4+w5+w8</field><field+name%3D"popularity">7</field></doc></add>}{add=[7
 (1589913402043531264)]} 0 12
   [junit4]   2> 27006 INFO  (qtp531507010-89) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={stream.body=<add><doc><field+name%3D"id">8</field><field+name%3D"title">w1+w1+w1+w2+w2+w8</field><field+name%3D"description">w1+w1+w1+w2+w2</field><field+name%3D"popularity">8</field></doc></add>}{add=[8
 (1589913402061357056)]} 0 1
   [junit4]   2> 27013 INFO  (qtp531507010-87) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1589913402068697088,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 27013 INFO  (qtp531507010-87) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@306e4e61 
commitCommandVersion:1589913402068697088
   [junit4]   2> 27377 INFO  (qtp531507010-87) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7809dc55[collection1] main]
   [junit4]   2> 27378 INFO  (qtp531507010-87) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 27379 INFO  
(searcherExecutor-70-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7809dc55[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):C8)))}
   [junit4]   2> 27379 INFO  (qtp531507010-87) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={stream.body=<commit/>}{commit=} 0 366
   [junit4]   2> 27390 INFO  
(TEST-TestUserTermScoreWithQ.testUserTermScoreWithQ-seed#[DFE82B7B342E5B03]) [  
  ] o.a.s.SolrTestCaseJ4 ###Starting testUserTermScoreWithQ
   [junit4]   2> 27390 INFO  
(TEST-TestUserTermScoreWithQ.testUserTermScoreWithQ-seed#[DFE82B7B342E5B03]) [  
  ] o.a.s.l.TestRerankBase loading feauture 
   [junit4]   2> {
   [junit4]   2> "name":"SomeTermQ",
   [junit4]   2> "store":"test",
   [junit4]   2> "class":"org.apache.solr.ltr.feature.SolrFeature",
   [junit4]   2> "params":{"q":"{!terms f=popularity}88888"}
   [junit4]   2> }
   [junit4]   2>  
   [junit4]   2> 27393 INFO  (qtp531507010-89) [    x:collection1] 
o.e.j.s.h.C.solr SolrSchemaRestApi: [Restlet] ServerServlet: component class is 
null
   [junit4]   2> 27395 INFO  (qtp531507010-89) [    x:collection1] 
o.e.j.s.h.C.solr SolrSchemaRestApi: [Restlet] Attaching application: 
org.apache.solr.rest.SolrSchemaRestApi@54d869b1 to URI: /solr/schema
   [junit4]   2> Jan 17, 2018 11:12:57 PM org.restlet.Application start
   [junit4]   2> INFO: Starting org.apache.solr.rest.SolrSchemaRestApi 
application
   [junit4]   2> 27418 INFO  (qtp531507010-89) [    x:collection1] 
o.a.s.r.SolrSchemaRestApi createInboundRoot started for /schema
   [junit4]   2> 27419 INFO  (qtp531507010-89) [    x:collection1] 
o.a.s.r.RestManager Attached managed resource at path: /feature-store
   [junit4]   2> 27420 INFO  (qtp531507010-89) [    x:collection1] 
o.a.s.r.RestManager Attached managed resource at path: /managed
   [junit4]   2> 27420 INFO  (qtp531507010-89) [    x:collection1] 
o.a.s.r.RestManager Attached managed resource at path: /model-store
   [junit4]   2> 27420 INFO  (qtp531507010-89) [    x:collection1] 
o.a.s.r.RestManager Attached 3 ManagedResource endpoints to Restlet router: 
/schema
   [junit4]   2> 27420 INFO  (qtp531507010-89) [    x:collection1] 
o.a.s.r.SolrSchemaRestApi createInboundRoot complete for /schema
   [junit4]   2> 27421 INFO  (qtp531507010-89) [    x:collection1] 
o.a.s.r.RestManager Found ManagedResource 
[org.apache.solr.ltr.store.rest.ManagedFeatureStore@581629cd] for 
/schema/feature-store
   [junit4]   2> 27421 INFO  (qtp531507010-89) [    x:collection1] 
o.a.s.r.ManagedResource Processing update to /schema/feature-store: 
{name=SomeTermQ, store=test, class=org.apache.solr.ltr.feature.SolrFeature, 
params={q={!terms f=popularity}88888}} is a java.util.LinkedHashMap
   [junit4]   2> 27421 INFO  (qtp531507010-89) [    x:collection1] 
o.a.s.l.s.r.ManagedFeatureStore register feature based on {name=SomeTermQ, 
store=test, class=org.apache.solr.ltr.feature.SolrFeature, params={q={!terms 
f=popularity}88888}}
   [junit4]   2> 27449 INFO  (qtp531507010-89) [    x:collection1] 
o.a.s.r.ManagedResourceStorage Saved JSON object to path 
_schema_feature-store.json using 
file:dir=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestUserTermScoreWithQ_DFE82B7B342E5B03-001/tempDir-001/collection1/conf
   [junit4]   2> 27449 INFO  (qtp531507010-89) [    x:collection1] 
o.a.s.r.RestManager [collection1]  webapp=/solr path=/schema/feature-store 
params={indent=on&wt=json} status=0 QTime=57
   [junit4]   2> Jan 17, 2018 11:12:57 PM org.restlet.engine.log.LogFilter 
afterHandle
   [junit4]   2> INFO: 2018-01-17       23:12:57        127.0.0.1       -       
127.0.0.1       64958   PUT     /solr/schema/feature-store      
wt=json&indent=on       200     -       135     53      https://127.0.0.1:64958 
Apache-HttpClient/4.5.3 (Java/1.8.0_144)        -
   [junit4]   2> 27452 INFO  
(TEST-TestUserTermScoreWithQ.testUserTermScoreWithQ-seed#[DFE82B7B342E5B03]) [  
  ] o.a.s.l.TestRerankBase loading model 
   [junit4]   2> {
   [junit4]   2> "name":"Term-modelQ",
   [junit4]   2> "store":"test",
   [junit4]   2> "class":"org.apache.solr.ltr.model.LinearModel",
   [junit4]   2> "features":[
   [junit4]   2>        { "name":"SomeTermQ"}
   [junit4]   2> ]
   [junit4]   2> ,
   [junit4]   2> "params":{"weights":{"SomeTermQ":1.0}}
   [junit4]   2> }
   [junit4]   2>  
   [junit4]   2> 27454 INFO  (qtp531507010-87) [    x:collection1] 
o.a.s.r.RestManager Found ManagedResource [ManagedModelStore [store=ModelStore 
[availableModels=[]], 
featureStores=org.apache.solr.ltr.store.rest.ManagedFeatureStore@581629cd]] for 
/schema/model-store
   [junit4]   2> 27456 INFO  (qtp531507010-87) [    x:collection1] 
o.a.s.r.ManagedResource Processing update to /schema/model-store: 
{name=Term-modelQ, store=test, class=org.apache.solr.ltr.model.LinearModel, 
features=[{name=SomeTermQ}], params={weights={SomeTermQ=1.0}}} is a 
java.util.LinkedHashMap
   [junit4]   2> 27483 INFO  (qtp531507010-87) [    x:collection1] 
o.a.s.l.s.r.ManagedModelStore adding model Term-modelQ
   [junit4]   2> 27484 INFO  (qtp531507010-87) [    x:collection1] 
o.a.s.r.ManagedResourceStorage Saved JSON object to path 
_schema_model-store.json using 
file:dir=/Users/jenkins/workspace/Lucene-Solr-7.x-MacOSX/solr/build/contrib/solr-ltr/test/J0/temp/solr.ltr.feature.TestUserTermScoreWithQ_DFE82B7B342E5B03-001/tempDir-001/collection1/conf
   [junit4]   2> 27484 INFO  (qtp531507010-87) [    x:collection1] 
o.a.s.r.RestManager [collection1]  webapp=/solr path=/schema/model-store 
params={indent=on&wt=json} status=0 QTime=31
   [junit4]   2> Jan 17, 2018 11:12:57 PM org.restlet.engine.log.LogFilter 
afterHandle
   [junit4]   2> INFO: 2018-01-17       23:12:57        127.0.0.1       -       
127.0.0.1       64958   PUT     /solr/schema/model-store        
wt=json&indent=on       200     -       170     30      https://127.0.0.1:64958 
Apache-HttpClient/4.5.3 (Java/1.8.0_144)        -
   [junit4]   2> 27628 INFO  (qtp531507010-89) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/query 
params={q=title:w1&indent=on&fl=*,+score&rows=4&wt=json&debugQuery=on&rq={!ltr+model%3DTerm-modelQ+reRankDocs%3D4}}
 hits=4 status=0 QTime=139
   [junit4]   2> 27637 INFO  (qtp531507010-87) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/query 
params={q=title:w1&indent=on&fl=*,+score&rows=4&wt=json&debugQuery=on&rq={!ltr+model%3DTerm-modelQ+reRankDocs%3D4}}
 hits=4 status=0 QTime=6
   [junit4]   2> 27644 INFO  (qtp531507010-89) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/query 
params={q=title:w1&indent=on&fl=*,+score&rows=4&wt=json&debugQuery=on&rq={!ltr+model%3DTerm-modelQ+reRankDocs%3D4}}
 hits=4 status=0 QTime=3
   [junit4]   2> 27646 INFO  
(TEST-TestUserTermScoreWithQ.testUserTermScoreWithQ-seed#[DFE82B7B342E5B03]) [  
  ] o.a.s.SolrTestCaseJ4 ###Ending testUserTermScoreWithQ
   [junit4]   2> 27651 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.e.j.s.AbstractConnector Stopped ServerConnector@5713ccab{SSL,[ssl, 
http/1.1]}{127.0.0.1:0}
   [junit4]   2> 27651 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1905165106
   [junit4]   2> 27651 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 27651 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 27651 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 27652 INFO  (coreCloseExecutor-75-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@7e2294fd
   [junit4]   2> 27653 INFO  (coreCloseExecutor-75-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=2116195581
   [junit4]   2> 27668 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@5709853c{/solr,null,UNAVAILABLE}
   [junit4]   2> 27669 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.e.j.s.session Stopped scavenging
   [junit4]   2> 57693 WARN  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.e.j.u.t.QueuedThreadPool 
QueuedThreadPool@qtp531507010{STOPPING,8<=8<=10000,i=0,q=1} Couldn't stop 
Thread[qtp531507010-89,5,TGRP-TestUserTermScoreWithQ]
   [junit4]   2> 57700 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 57701 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1979240355
   [junit4]   2> 57701 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 57701 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@59d17b47: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ null
   [junit4]   2> 57701 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 57701 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@73fa2ade: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
null
   [junit4]   2> 57702 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 57702 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@50ee89e: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / null
   [junit4]   2> 57704 INFO  (coreCloseExecutor-77-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@4d14939d
   [junit4]   2> 57704 INFO  (coreCloseExecutor-77-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=1293194141
   [junit4]   2> 57705 INFO  (coreCloseExecutor-77-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@37d36e68: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / null
   [junit4]   2> 57741 INFO  
(SUITE-TestUserTermScoreWithQ-seed#[DFE82B7B342E5B03]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> Jan 18, 2018 7:13:27 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> Jan 18, 2018 7:13:47 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> SEVERE: 1 thread leaked from SUITE scope at 
org.apache.solr.ltr.feature.TestUserTermScoreWithQ: 
   [junit4]   2>    1) Thread[id=89, name=qtp531507010-89, state=TIMED_WAITING, 
group=TGRP-TestUserTermScoreWithQ]
   [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 18, 2018 7:13:47 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
   [junit4]   2> INFO: Starting to interrupt leaked threads:
   [junit4]   2>    1) Thread[id=89, name=qtp531507010-89, state=TIMED_WAITING, 
group=TGRP-TestUserTermScoreWithQ]
   [junit4]   2> Jan 18, 2018 7:13:50 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
   [junit4]   2> SEVERE: There are still zombie threads that couldn't be 
terminated:
   [junit4]   2>    1) Thread[id=89, name=qtp531507010-89, state=TIMED_WAITING, 
group=TGRP-TestUserTermScoreWithQ]
   [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=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY,
 chunkSize=9, maxDocsPerChunk=10, blockSize=6), 
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, 
chunkSize=9, blockSize=6)), sim=RandomSimilarity(queryNorm=true): {}, 
locale=id-ID, timezone=America/Tijuana
   [junit4]   2> NOTE: Mac OS X 10.11.6 x86_64/Oracle Corporation 1.8.0_144 
(64-bit)/cpus=3,threads=2,free=112279184,total=198180864
   [junit4]   2> NOTE: All tests run in this JVM: [TestLTRWithSort, 
TestUserTermScorereQDF, TestUserTermScoreWithQ]
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestUserTermScoreWithQ -Dtests.seed=DFE82B7B342E5B03 
-Dtests.slow=true -Dtests.locale=id-ID -Dtests.timezone=America/Tijuana 
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.00s J0 | TestUserTermScoreWithQ (suite) <<<
   [junit4]    > Throwable #1: 
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE 
scope at org.apache.solr.ltr.feature.TestUserTermScoreWithQ: 
   [junit4]    >    1) Thread[id=89, name=qtp531507010-89, state=TIMED_WAITING, 
group=TGRP-TestUserTermScoreWithQ]
   [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([DFE82B7B342E5B03]:0)Throwable #2: 
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie 
threads that couldn't be terminated:
   [junit4]    >    1) Thread[id=89, name=qtp531507010-89, state=TIMED_WAITING, 
group=TGRP-TestUserTermScoreWithQ]
   [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([DFE82B7B342E5B03]:0)
   [junit4] Completed [29/35 (1!)] on J0 in 56.88s, 1 test, 2 errors <<< 
FAILURES!

[...truncated 40780 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to