Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.x/393/

2 tests failed.
FAILED:  
junit.framework.TestSuite.org.apache.solr.search.facet.TestJsonFacetRefinement

Error Message:
1 thread leaked from SUITE scope at 
org.apache.solr.search.facet.TestJsonFacetRefinement:     1) Thread[id=2264, 
name=qtp199107231-2264, state=TIMED_WAITING, 
group=TGRP-TestJsonFacetRefinement]         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.search.facet.TestJsonFacetRefinement: 
   1) Thread[id=2264, name=qtp199107231-2264, state=TIMED_WAITING, 
group=TGRP-TestJsonFacetRefinement]
        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([8FD30D0F3B7F8B49]:0)


FAILED:  
junit.framework.TestSuite.org.apache.solr.search.facet.TestJsonFacetRefinement

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




Build Log:
[...truncated 12280 lines...]
   [junit4] Suite: org.apache.solr.search.facet.TestJsonFacetRefinement
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/init-core-data-001
   [junit4]   2> 236170 WARN  
(SUITE-TestJsonFacetRefinement-seed#[8FD30D0F3B7F8B49]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 236170 INFO  
(SUITE-TestJsonFacetRefinement-seed#[8FD30D0F3B7F8B49]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 236171 INFO  
(SUITE-TestJsonFacetRefinement-seed#[8FD30D0F3B7F8B49]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=None)
   [junit4]   2> 236171 INFO  
(SUITE-TestJsonFacetRefinement-seed#[8FD30D0F3B7F8B49]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 236172 INFO  
(SUITE-TestJsonFacetRefinement-seed#[8FD30D0F3B7F8B49]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 236172 INFO  
(SUITE-TestJsonFacetRefinement-seed#[8FD30D0F3B7F8B49]-worker) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 236213 INFO  
(SUITE-TestJsonFacetRefinement-seed#[8FD30D0F3B7F8B49]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
   [junit4]   2> 236297 INFO  
(SUITE-TestJsonFacetRefinement-seed#[8FD30D0F3B7F8B49]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=example
   [junit4]   2> 236391 INFO  
(SUITE-TestJsonFacetRefinement-seed#[8FD30D0F3B7F8B49]-worker) [    ] 
o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
   [junit4]   2> 236445 INFO  
(SUITE-TestJsonFacetRefinement-seed#[8FD30D0F3B7F8B49]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1af6edef
   [junit4]   2> 236456 INFO  
(SUITE-TestJsonFacetRefinement-seed#[8FD30D0F3B7F8B49]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1af6edef
   [junit4]   2> 236457 INFO  
(SUITE-TestJsonFacetRefinement-seed#[8FD30D0F3B7F8B49]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1af6edef
   [junit4]   2> 236460 INFO  (coreLoadExecutor-611-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 236461 INFO  (coreLoadExecutor-611-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 236497 INFO  (coreLoadExecutor-611-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
   [junit4]   2> 236514 INFO  (coreLoadExecutor-611-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example
   [junit4]   2> 236567 INFO  (coreLoadExecutor-611-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid 
field id
   [junit4]   2> 236572 INFO  (coreLoadExecutor-611-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 236572 INFO  (coreLoadExecutor-611-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@1af6edef
   [junit4]   2> 236572 INFO  (coreLoadExecutor-611-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 236572 INFO  (coreLoadExecutor-611-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/init-core-data-001/]
   [junit4]   2> 236574 INFO  (coreLoadExecutor-611-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=33, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=26.197265625, 
floorSegmentMB=0.37890625, forceMergeDeletesPctAllowed=28.562695229273956, 
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5980774975131999
   [junit4]   2> 236576 WARN  (coreLoadExecutor-611-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler 
{type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, class=DumpRequestHandler},args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 236605 INFO  (coreLoadExecutor-611-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 236605 INFO  (coreLoadExecutor-611-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 236606 INFO  (coreLoadExecutor-611-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 236606 INFO  (coreLoadExecutor-611-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 236608 INFO  (coreLoadExecutor-611-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=27, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5951497369215825]
   [junit4]   2> 236608 INFO  (coreLoadExecutor-611-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@4bc8e108[collection1] main]
   [junit4]   2> 236608 WARN  (coreLoadExecutor-611-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 236609 INFO  (coreLoadExecutor-611-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 236610 INFO  
(searcherExecutor-612-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4bc8e108[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 236611 INFO  (coreLoadExecutor-611-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1592435373593591808
   [junit4]   2> 236614 INFO  
(SUITE-TestJsonFacetRefinement-seed#[8FD30D0F3B7F8B49]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 236616 INFO  
(TEST-TestJsonFacetRefinement.testManyClauses-seed#[8FD30D0F3B7F8B49]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testManyClauses
   [junit4]   2> 236624 INFO  
(TEST-TestJsonFacetRefinement.testManyClauses-seed#[8FD30D0F3B7F8B49]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={q=id:(z0+z1+z2+z3+z4+z5+z6+z7+z8+z9+z10+z11+z12+z13+z14+z15+z16+z17+z18+z19+z20+z21+z22+z23+z24+z25+z26+z27+z28+z29+z30+z31+z32+z33+z34+z35+z36+z37+z38+z39+z40+z41+z42+z43+z44+z45+z46+z47+z48+z49+z50+z51+z52+z53+z54+z55+z56+z57+z58+z59+z60+z61+z62+z63+z64+z65+z66+z67+z68+z69+z70+z71+z72+z73+z74+z75+z76+z77+z78+z79+z80+z81+z82+z83+z84+z85+z86+z87+z88+z89+z90+z91+z92+z93+z94+z95+z96+z97+z98+z99+z100+z101+z102+z103+z104+z105+z106+z107+z108+z109+z110+z111+z112+z113+z114+z115+z116+z117+z118+z119+z120+z121+z122+z123+z124+z125+z126+z127+z128+z129+z130+z131+z132+z133+z134+z135+z136+z137+z138+z139+z140+z141+z142+z143+z144+z145+z146+z147+z148+z149+z150+z151+z152+z153+z154+z155+z156+z157+z158+z159+z160+z161+z162+z163+z164+z165+z166+z167+z168+z169+z170+z171+z172+z173+z174+z175+z176+z177+z178+z179+z180+z181+z182+z183+z184+z185+z186+z187+z188+z189+z190+z191+z192+z193+z194+z195+z196+z197+z198+z199+z200+z201+z202+z203+z204+z205+z206+z207+z208+z209+z210+z211+z212+z213+z214+z215+z216+z217+z218+z219+z220+z221+z222+z223+z224+z225+z226+z227+z228+z229+z230+z231+z232+z233+z234+z235+z236+z237+z238+z239+z240+z241+z242+z243+z244+z245+z246+z247+z248+z249+z250+z251+z252+z253+z254+z255+z256+z257+z258+z259+z260+z261+z262+z263+z264+z265+z266+z267+z268+z269+z270+z271+z272+z273+z274+z275+z276+z277+z278+z279+z280+z281+z282+z283+z284+z285+z286+z287+z288+z289+z290+z291+z292+z293+z294+z295+z296+z297+z298+z299+z300+z301+z302+z303+z304+z305+z306+z307+z308+z309+z310+z311+z312+z313+z314+z315+z316+z317+z318+z319+z320+z321+z322+z323+z324+z325+z326+z327+z328+z329+z330+z331+z332+z333+z334+z335+z336+z337+z338+z339+z340+z341+z342+z343+z344+z345+z346+z347+z348+z349+z350+z351+z352+z353+z354+z355+z356+z357+z358+z359+z360+z361+z362+z363+z364+z365+z366+z367+z368+z369+z370+z371+z372+z373+z374+z375+z376+z377+z378+z379+z380+z381+z382+z383+z384+z385+z386+z387+z388+z389+z390+z391+z392+z393+z394+z395+z396+z397+z398+z399+z400+z401+z402+z403+z404+z405+z406+z407+z408+z409+z410+z411+z412+z413+z414+z415+z416+z417+z418+z419+z420+z421+z422+z423+z424+z425+z426+z427+z428+z429+z430+z431+z432+z433+z434+z435+z436+z437+z438+z439+z440+z441+z442+z443+z444+z445+z446+z447+z448+z449+z450+z451+z452+z453+z454+z455+z456+z457+z458+z459+z460+z461+z462+z463+z464+z465+z466+z467+z468+z469+z470+z471+z472+z473+z474+z475+z476+z477+z478+z479+z480+z481+z482+z483+z484+z485+z486+z487+z488+z489+z490+z491+z492+z493+z494+z495+z496+z497+z498+z499+z500+z501+z502+z503+z504+z505+z506+z507+z508+z509+z510+z511+z512+z513+z514+z515+z516+z517+z518+z519+z520+z521+z522+z523+z524+z525+z526+z527+z528+z529+z530+z531+z532+z533+z534+z535+z536+z537+z538+z539+z540+z541+z542+z543+z544+z545+z546+z547+z548+z549+z550+z551+z552+z553+z554+z555+z556+z557+z558+z559+z560+z561+z562+z563+z564+z565+z566+z567+z568+z569+z570+z571+z572+z573+z574+z575+z576+z577+z578+z579+z580+z581+z582+z583+z584+z585+z586+z587+z588+z589+z590+z591+z592+z593+z594+z595+z596+z597+z598+z599+z600+z601+z602+z603+z604+z605+z606+z607+z608+z609+z610+z611+z612+z613+z614+z615+z616+z617+z618+z619+z620+z621+z622+z623+z624+z625+z626+z627+z628+z629+z630+z631+z632+z633+z634+z635+z636+z637+z638+z639+z640+z641+z642+z643+z644+z645+z646+z647+z648+z649+z650+z651+z652+z653+z654+z655+z656+z657+z658+z659+z660+z661+z662+z663+z664+z665+z666+z667+z668+z669+z670+z671+z672+z673+z674+z675+z676+z677+z678+z679+z680+z681+z682+z683+z684+z685+z686+z687+z688+z689+z690+z691+z692+z693+z694+z695+z696+z697+z698+z699+z700+z701+z702+z703+z704+z705+z706+z707+z708+z709+z710+z711+z712+z713+z714+z715+z716+z717+z718+z719+z720+z721+z722+z723+z724+z725+z726+z727+z728+z729+z730+z731+z732+z733+z734+z735+z736+z737+z738+z739+z740+z741+z742+z743+z744+z745+z746+z747+z748+z749+z750+z751+z752+z753+z754+z755+z756+z757+z758+z759+z760+z761+z762+z763+z764+z765+z766+z767+z768+z769+z770+z771+z772+z773+z774+z775+z776+z777+z778+z779+z780+z781+z782+z783+z784+z785+z786+z787+z788+z789+z790+z791+z792+z793+z794+z795+z796+z797+z798+z799+z800+z801+z802+z803+z804+z805+z806+z807+z808+z809+z810+z811+z812+z813+z814+z815+z816+z817+z818+z819+z820+z821+z822+z823+z824+z825+z826+z827+z828+z829+z830+z831+z832+z833+z834+z835+z836+z837+z838+z839+z840+z841+z842+z843+z844+z845+z846+z847+z848+z849+z850+z851+z852+z853+z854+z855+z856+z857+z858+z859+z860+z861+z862+z863+z864+z865+z866+z867+z868+z869+z870+z871+z872+z873+z874+z875+z876+z877+z878+z879+z880+z881+z882+z883+z884+z885+z886+z887+z888+z889+z890+z891+z892+z893+z894+z895+z896+z897+z898+z899+z900+z901+z902+z903+z904+z905+z906+z907+z908+z909+z910+z911+z912+z913+z914+z915+z916+z917+z918+z919+z920+z921+z922+z923+z924+z925+z926+z927+z928+z929+z930+z931+z932+z933+z934+z935+z936+z937+z938+z939+z940+z941+z942+z943+z944+z945+z946+z947+z948+z949+z950+z951+z952+z953+z954+z955+z956+z957+z958+z959+z960+z961+z962+z963+z964+z965+z966+z967+z968+z969+z970+z971+z972+z973+z974+z975+z976+z977+z978+z979+z980+z981+z982+z983+z984+z985+z986+z987+z988+z989+z990+z991+z992+z993+z994+z995+z996+z997+z998+z999+z1000+z1001+z1002+z1003+z1004+z1005+z1006+z1007+z1008+z1009+z1010+z1011+z1012+z1013+z1014+z1015+z1016+z1017+z1018+z1019+z1020+z1021+z1022+z1023+1+a+2+b+3+c+10+d+11+12+)&wt=xml}
 hits=0 status=0 QTime=7
   [junit4]   2> 236625 INFO  
(TEST-TestJsonFacetRefinement.testManyClauses-seed#[8FD30D0F3B7F8B49]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testManyClauses
   [junit4]   2> 236627 INFO  
(TEST-TestJsonFacetRefinement.testMerge-seed#[8FD30D0F3B7F8B49]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testMerge
   [junit4]   2> 236636 INFO  
(TEST-TestJsonFacetRefinement.testMerge-seed#[8FD30D0F3B7F8B49]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testMerge
   [junit4]   2> 236644 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.SolrTestCaseJ4 ###Starting testBasicRefinement
   [junit4]   2> 236650 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, 
git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 236656 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 236656 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 236656 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 236657 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@7f5f68e0{/solr,null,AVAILABLE}
   [junit4]   2> 236657 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.e.j.s.AbstractConnector Started 
ServerConnector@7e4e456f{HTTP/1.1,[http/1.1]}{127.0.0.1:45284}
   [junit4]   2> 236657 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.e.j.s.Server Started @238365ms
   [junit4]   2> 236657 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema_latest.xml, 
solrconfig=solrconfig-tlog.xml, hostContext=/solr, hostPort=45284}
   [junit4]   2> 236657 ERROR 
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 236658 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.3.0
   [junit4]   2> 236658 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 236658 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 236658 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-02-15T03:18:36.380Z
   [junit4]   2> 236659 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/s0-001/solr.xml
   [junit4]   2> 236662 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 236662 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 236664 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1af6edef, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 236691 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1af6edef
   [junit4]   2> 236700 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1af6edef
   [junit4]   2> 236700 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1af6edef
   [junit4]   2> 236702 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/s0-001/.
   [junit4]   2> 236702 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 236706 INFO  (coreLoadExecutor-622-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 236727 INFO  (coreLoadExecutor-622-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
   [junit4]   2> 236752 INFO  (coreLoadExecutor-622-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example
   [junit4]   2> 236796 INFO  (coreLoadExecutor-622-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid 
field id
   [junit4]   2> 236802 INFO  (coreLoadExecutor-622-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/s0-001/./collection1,
 trusted=true
   [junit4]   2> 236802 INFO  (coreLoadExecutor-622-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@1af6edef
   [junit4]   2> 236802 INFO  (coreLoadExecutor-622-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 236802 INFO  (coreLoadExecutor-622-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/s0-001/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/s0-001/./collection1/data/]
   [junit4]   2> 236804 INFO  (coreLoadExecutor-622-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=33, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=26.197265625, 
floorSegmentMB=0.37890625, forceMergeDeletesPctAllowed=28.562695229273956, 
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5980774975131999
   [junit4]   2> 236807 WARN  (coreLoadExecutor-622-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler 
{type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, class=DumpRequestHandler},args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 236865 INFO  (coreLoadExecutor-622-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 236865 INFO  (coreLoadExecutor-622-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 236866 INFO  (coreLoadExecutor-622-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 236866 INFO  (coreLoadExecutor-622-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 236868 INFO  (coreLoadExecutor-622-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=27, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5951497369215825]
   [junit4]   2> 236868 INFO  (coreLoadExecutor-622-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@203523dc[collection1] main]
   [junit4]   2> 236869 INFO  (coreLoadExecutor-622-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/s0-001/collection1/conf
   [junit4]   2> 236870 INFO  (coreLoadExecutor-622-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 236871 INFO  
(searcherExecutor-623-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@203523dc[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 236871 INFO  (coreLoadExecutor-622-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1592435373866221568
   [junit4]   2> 237373 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.SolrTestCaseHS ===> Started solr server port=45284 
home=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/s0-001
   [junit4]   2> 237379 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, 
git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 237380 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 237380 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 237380 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 237380 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@51fb29fa{/solr,null,AVAILABLE}
   [junit4]   2> 237381 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.e.j.s.AbstractConnector Started 
ServerConnector@263dc213{HTTP/1.1,[http/1.1]}{127.0.0.1:42251}
   [junit4]   2> 237381 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.e.j.s.Server Started @239088ms
   [junit4]   2> 237381 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema_latest.xml, 
solrconfig=solrconfig-tlog.xml, hostContext=/solr, hostPort=42251}
   [junit4]   2> 237381 ERROR 
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 237381 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.3.0
   [junit4]   2> 237381 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 237381 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 237381 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-02-15T03:18:37.103Z
   [junit4]   2> 237382 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/s1-001/solr.xml
   [junit4]   2> 237388 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 237388 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 237389 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1af6edef, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 237416 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1af6edef
   [junit4]   2> 237425 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1af6edef
   [junit4]   2> 237425 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1af6edef
   [junit4]   2> 237427 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/s1-001/.
   [junit4]   2> 237427 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 237436 INFO  (coreLoadExecutor-633-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 237481 INFO  (coreLoadExecutor-633-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
   [junit4]   2> 237491 INFO  (coreLoadExecutor-633-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example
   [junit4]   2> 237535 INFO  (coreLoadExecutor-633-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid 
field id
   [junit4]   2> 237549 INFO  (coreLoadExecutor-633-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/s1-001/./collection1,
 trusted=true
   [junit4]   2> 237549 INFO  (coreLoadExecutor-633-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@1af6edef
   [junit4]   2> 237549 INFO  (coreLoadExecutor-633-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 237550 INFO  (coreLoadExecutor-633-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/s1-001/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/s1-001/./collection1/data/]
   [junit4]   2> 237551 INFO  (coreLoadExecutor-633-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=33, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=26.197265625, 
floorSegmentMB=0.37890625, forceMergeDeletesPctAllowed=28.562695229273956, 
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5980774975131999
   [junit4]   2> 237557 WARN  (coreLoadExecutor-633-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler 
{type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, class=DumpRequestHandler},args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 237589 INFO  (coreLoadExecutor-633-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 237589 INFO  (coreLoadExecutor-633-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 237590 INFO  (coreLoadExecutor-633-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 237590 INFO  (coreLoadExecutor-633-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 237591 INFO  (coreLoadExecutor-633-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=27, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5951497369215825]
   [junit4]   2> 237591 INFO  (coreLoadExecutor-633-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@509e0fdf[collection1] main]
   [junit4]   2> 237591 INFO  (coreLoadExecutor-633-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/s1-001/collection1/conf
   [junit4]   2> 237592 INFO  (coreLoadExecutor-633-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 237592 INFO  (coreLoadExecutor-633-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1592435374622244864
   [junit4]   2> 237603 INFO  
(searcherExecutor-634-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@509e0fdf[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 238094 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.SolrTestCaseHS ===> Started solr server port=42251 
home=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/s1-001
   [junit4]   2> 238099 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-21T21:27:37Z, 
git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 238100 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 238100 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 238100 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.e.j.s.session Scavenging every 660000ms
   [junit4]   2> 238101 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@6220824f{/solr,null,AVAILABLE}
   [junit4]   2> 238101 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.e.j.s.AbstractConnector Started 
ServerConnector@2c7c4971{HTTP/1.1,[http/1.1]}{127.0.0.1:35318}
   [junit4]   2> 238101 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.e.j.s.Server Started @239809ms
   [junit4]   2> 238101 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema_latest.xml, 
solrconfig=solrconfig-tlog.xml, hostContext=/solr, hostPort=35318}
   [junit4]   2> 238102 ERROR 
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 238102 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.3.0
   [junit4]   2> 238102 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 238102 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 238102 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-02-15T03:18:37.824Z
   [junit4]   2> 238102 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/s2-001/solr.xml
   [junit4]   2> 238106 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverWorkLoopDelay is ignored
   [junit4]   2> 238106 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 238107 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@1af6edef, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 238135 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 
'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1af6edef
   [junit4]   2> 238143 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1af6edef
   [junit4]   2> 238144 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1af6edef
   [junit4]   2> 238146 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/s2-001/.
   [junit4]   2> 238146 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 238147 INFO  (coreLoadExecutor-644-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 238171 INFO  (coreLoadExecutor-644-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
   [junit4]   2> 238188 INFO  (coreLoadExecutor-644-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example
   [junit4]   2> 238232 INFO  (coreLoadExecutor-644-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid 
field id
   [junit4]   2> 238238 INFO  (coreLoadExecutor-644-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/s2-001/./collection1,
 trusted=true
   [junit4]   2> 238238 INFO  (coreLoadExecutor-644-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@1af6edef
   [junit4]   2> 238238 INFO  (coreLoadExecutor-644-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 238238 INFO  (coreLoadExecutor-644-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/s2-001/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/s2-001/./collection1/data/]
   [junit4]   2> 238240 INFO  (coreLoadExecutor-644-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=33, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=26.197265625, 
floorSegmentMB=0.37890625, forceMergeDeletesPctAllowed=28.562695229273956, 
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5980774975131999
   [junit4]   2> 238242 WARN  (coreLoadExecutor-644-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler 
{type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = 
{initParams=a, name=/dump, class=DumpRequestHandler},args = 
{defaults={a=A,b=B}}}
   [junit4]   2> 238273 INFO  (coreLoadExecutor-644-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 238273 INFO  (coreLoadExecutor-644-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 238274 INFO  (coreLoadExecutor-644-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 238274 INFO  (coreLoadExecutor-644-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 238275 INFO  (coreLoadExecutor-644-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=27, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.5951497369215825]
   [junit4]   2> 238275 INFO  (coreLoadExecutor-644-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@6b727460[collection1] main]
   [junit4]   2> 238276 INFO  (coreLoadExecutor-644-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage File-based storage initialized to 
use dir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/s2-001/collection1/conf
   [junit4]   2> 238276 INFO  (coreLoadExecutor-644-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 238278 INFO  
(searcherExecutor-645-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6b727460[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 238278 INFO  (coreLoadExecutor-644-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1592435375341568000
   [junit4]   2> 238280 INFO  
(TEST-TestJsonFacetRefinement.testBasicRefinement-seed#[8FD30D0F3B7F8B49]) [    
] o.a.s.SolrTestCaseHS ===> Started solr server port=35318 
home=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.x/solr/build/solr-core/test/J0/temp/solr.search.facet.TestJsonFacetRefinement_8FD30D0F3B7F8B49-001/s2-001
   [junit4]   2> 238288 INFO  (qtp199107231-2260) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1592435375348908032)} 0 3
   [junit4]   2> 238291 INFO  (qtp560764968-2276) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1592435375353102336)} 0 2
   [junit4]   2> 238295 INFO  (qtp422462028-2292) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1592435375356248064)} 0 2
   [junit4]   2> 238302 INFO  (qtp199107231-2262) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[01 (1592435375359393792)]} 0 7
   [junit4]   2> 238303 INFO  (qtp199107231-2264) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[02 (1592435375367782400)]} 0 0
   [junit4]   2> 238304 INFO  (qtp560764968-2278) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[11 (1592435375367782400)]} 0 0
   [junit4]   2> 238305 INFO  (qtp560764968-2280) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[12 (1592435375368830976)]} 0 0
   [junit4]   2> 238306 INFO  (qtp560764968-2278) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[13 (1592435375369879552)]} 0 0
   [junit4]   2> 238306 INFO  (qtp422462028-2294) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[21 (1592435375370928128)]} 0 0
   [junit4]   2> 238307 INFO  (qtp422462028-2296) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[22 (1592435375371976704)]} 0 0
   [junit4]   2> 238308 INFO  (qtp422462028-2294) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={wt=javabin&version=2}{add=[23 (1592435375373025280)]} 0 0
   [junit4]   2> 238315 INFO  (qtp199107231-2262) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1592435375380365312,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 238316 INFO  (qtp199107231-2262) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@c76bf57 
commitCommandVersion:1592435375380365312
   [junit4]   2> 238354 INFO  (qtp199107231-2262) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@2f62d93b[collection1] main]
   [junit4]   2> 238356 INFO  
(searcherExecutor-623-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2f62d93b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):C2)))}
   [junit4]   2> 238356 INFO  (qtp199107231-2262) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 238356 INFO  (qtp199107231-2262) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 40
   [junit4]   2> 238356 INFO  (qtp560764968-2280) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1592435375423356928,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 238356 INFO  (qtp560764968-2280) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5ce5a4e3 
commitCommandVersion:1592435375423356928
   [junit4]   2> 238371 INFO  (qtp560764968-2280) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@e134b58[collection1] main]
   [junit4]   2> 238372 INFO  (qtp560764968-2280) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 238373 INFO  
(searcherExecutor-634-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@e134b58[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):C3)))}
   [junit4]   2> 238373 INFO  (qtp560764968-2280) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 17
   [junit4]   2> 238374 INFO  (qtp422462028-2296) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1592435375442231296,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 238374 INFO  (qtp422462028-2296) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@778790d8 
commitCommandVersion:1592435375442231296
   [junit4]   2> 238377 INFO  (qtp422462028-2296) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@2a517f00[collection1] main]
   [junit4]   2> 238378 INFO  (qtp422462028-2296) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 238379 INFO  
(searcherExecutor-645-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2a517f00[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):C3)))}
   [junit4]   2> 238379 INFO  (qtp422462028-2296) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 5
   [junit4]   2> 238415 INFO  (qtp560764968-2281) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:42251/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:false}}&NOW=1518664718102&isShard=true&wt=javabin}
 hits=3 status=0 QTime=6
   [junit4]   2> 238415 INFO  (qtp199107231-2262) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:45284/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:false}}&NOW=1518664718102&isShard=true&wt=javabin}
 hits=2 status=0 QTime=15
   [junit4]   2> 238418 INFO  (qtp422462028-2296) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:35318/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:false}}&NOW=1518664718102&isShard=true&wt=javabin}
 hits=3 status=0 QTime=0
   [junit4]   2> 238420 INFO  (qtp560764968-2275) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&shards.purpose=64&cat_s=cat_s&shard.url=127.0.0.1:42251/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&NOW=1518664718102&ids=11,12,13&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238420 INFO  (qtp422462028-2297) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&shards.purpose=64&cat_s=cat_s&shard.url=127.0.0.1:35318/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&NOW=1518664718102&ids=22,23,21&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238421 INFO  (qtp199107231-2265) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&shards.purpose=64&cat_s=cat_s&shard.url=127.0.0.1:45284/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&NOW=1518664718102&ids=01,02&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238421 INFO  (qtp560764968-2278) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&shards=127.0.0.1:45284/solr/collection1,127.0.0.1:42251/solr/collection1,127.0.0.1:35318/solr/collection1&shards=debugQuery&shards=true&q=*:*&json.facet={cat0:{${terms}+type:terms,+field:${cat_s},+sort:'count+desc',+limit:1,+overrequest:0,+refine:false}}&indent=true&cat_s=cat_s&xy_s=xy_s&wt=json&version=2.2&er_s=er_s&num_d=num_d&qw_s=qw_s}
 hits=8 status=0 QTime=41
   [junit4]   2> 238447 INFO  (qtp560764968-2278) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:42251/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}&NOW=1518664718145&isShard=true&wt=javabin}
 hits=3 status=0 QTime=0
   [junit4]   2> 238457 INFO  (qtp422462028-2296) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:35318/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}&NOW=1518664718145&isShard=true&wt=javabin}
 hits=3 status=0 QTime=1
   [junit4]   2> 238457 INFO  (qtp199107231-2265) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:45284/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}&NOW=1518664718145&isShard=true&wt=javabin}
 hits=2 status=0 QTime=1
   [junit4]   2> 238478 INFO  (qtp422462028-2297) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&shards.purpose=64&cat_s=cat_s&shard.url=127.0.0.1:35318/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}&NOW=1518664718145&ids=22,23,21&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238478 INFO  (qtp560764968-2280) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={"refine":{"cat0":{"_l":["A"]}}}&shards.purpose=2097216&cat_s=cat_s&shard.url=127.0.0.1:42251/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}&NOW=1518664718145&ids=11,12,13&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238479 INFO  (qtp199107231-2264) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&shards.purpose=64&cat_s=cat_s&shard.url=127.0.0.1:45284/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}&NOW=1518664718145&ids=01,02&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238479 INFO  (qtp422462028-2294) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&shards=127.0.0.1:45284/solr/collection1,127.0.0.1:42251/solr/collection1,127.0.0.1:35318/solr/collection1&shards=debugQuery&shards=true&q=*:*&json.facet={cat0:{${terms}+type:terms,+field:${cat_s},+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}&indent=true&cat_s=cat_s&xy_s=xy_s&wt=json&version=2.2&er_s=er_s&num_d=num_d&qw_s=qw_s}
 hits=8 status=0 QTime=56
   [junit4]   2> 238497 INFO  (qtp422462028-2294) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:35318/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={cat0:{+type:terms,+field:cat_i,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}&NOW=1518664718202&isShard=true&wt=javabin}
 hits=3 status=0 QTime=15
   [junit4]   2> 238497 INFO  (qtp199107231-2265) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:45284/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={cat0:{+type:terms,+field:cat_i,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}&NOW=1518664718202&isShard=true&wt=javabin}
 hits=2 status=0 QTime=16
   [junit4]   2> 238507 INFO  (qtp560764968-2278) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:42251/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={cat0:{+type:terms,+field:cat_i,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}&NOW=1518664718202&isShard=true&wt=javabin}
 hits=3 status=0 QTime=26
   [junit4]   2> 238509 INFO  (qtp199107231-2264) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&shards.purpose=64&cat_s=cat_s&shard.url=127.0.0.1:45284/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={cat0:{+type:terms,+field:cat_i,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}&NOW=1518664718202&ids=01,02&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238509 INFO  (qtp422462028-2297) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&shards.purpose=64&cat_s=cat_s&shard.url=127.0.0.1:35318/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={cat0:{+type:terms,+field:cat_i,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}&NOW=1518664718202&ids=22,23,21&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238521 INFO  (qtp560764968-2280) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={"refine":{"cat0":{"_l":[1]}}}&shards.purpose=2097216&cat_s=cat_s&shard.url=127.0.0.1:42251/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={cat0:{+type:terms,+field:cat_i,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}&NOW=1518664718202&ids=11,12,13&isShard=true&wt=javabin}
 status=0 QTime=12
   [junit4]   2> 238522 INFO  (qtp422462028-2292) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&shards=127.0.0.1:45284/solr/collection1,127.0.0.1:42251/solr/collection1,127.0.0.1:35318/solr/collection1&shards=debugQuery&shards=true&q=*:*&json.facet={cat0:{${terms}+type:terms,+field:${cat_i},+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}&indent=true&cat_s=cat_s&xy_s=xy_s&wt=json&version=2.2&er_s=er_s&num_d=num_d&qw_s=qw_s}
 hits=8 status=0 QTime=41
   [junit4]   2> 238525 INFO  (qtp199107231-2266) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:45284/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={q1+:+{+type:query,+q:'*:*',+facet:{cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}}}&NOW=1518664718245&isShard=true&wt=javabin}
 hits=2 status=0 QTime=0
   [junit4]   2> 238525 INFO  (qtp560764968-2281) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:42251/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={q1+:+{+type:query,+q:'*:*',+facet:{cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}}}&NOW=1518664718245&isShard=true&wt=javabin}
 hits=3 status=0 QTime=1
   [junit4]   2> 238528 INFO  (qtp422462028-2298) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:35318/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={q1+:+{+type:query,+q:'*:*',+facet:{cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}}}&NOW=1518664718245&isShard=true&wt=javabin}
 hits=3 status=0 QTime=3
   [junit4]   2> 238529 INFO  (qtp422462028-2292) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&shards.purpose=64&cat_s=cat_s&shard.url=127.0.0.1:35318/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={q1+:+{+type:query,+q:'*:*',+facet:{cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}}}&NOW=1518664718245&ids=22,23,21&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238529 INFO  (qtp199107231-2264) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&shards.purpose=64&cat_s=cat_s&shard.url=127.0.0.1:45284/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={q1+:+{+type:query,+q:'*:*',+facet:{cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}}}&NOW=1518664718245&ids=01,02&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238529 INFO  (qtp560764968-2280) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={"refine":{"q1":{"cat0":{"_l":["A"]}}}}&shards.purpose=2097216&cat_s=cat_s&shard.url=127.0.0.1:42251/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={q1+:+{+type:query,+q:'*:*',+facet:{cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}}}&NOW=1518664718245&ids=11,12,13&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238530 INFO  (qtp560764968-2278) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&shards=127.0.0.1:45284/solr/collection1,127.0.0.1:42251/solr/collection1,127.0.0.1:35318/solr/collection1&shards=debugQuery&shards=true&q=*:*&json.facet={q1+:+{+type:query,+q:'*:*',+facet:{cat0:{${terms}+type:terms,+field:${cat_s},+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}}}&indent=true&cat_s=cat_s&xy_s=xy_s&wt=json&version=2.2&er_s=er_s&num_d=num_d&qw_s=qw_s}
 hits=8 status=0 QTime=6
   [junit4]   2> 238535 INFO  (qtp560764968-2281) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:42251/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={r1+:+{+type:range,+field:num_d+start:-20,+end:20,+gap:40+++,+facet:{cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}}}&NOW=1518664718252&isShard=true&wt=javabin}
 hits=3 status=0 QTime=3
   [junit4]   2> 238535 INFO  (qtp199107231-2266) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:45284/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={r1+:+{+type:range,+field:num_d+start:-20,+end:20,+gap:40+++,+facet:{cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}}}&NOW=1518664718252&isShard=true&wt=javabin}
 hits=2 status=0 QTime=3
   [junit4]   2> 238535 INFO  (qtp422462028-2298) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:35318/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={r1+:+{+type:range,+field:num_d+start:-20,+end:20,+gap:40+++,+facet:{cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}}}&NOW=1518664718252&isShard=true&wt=javabin}
 hits=3 status=0 QTime=3
   [junit4]   2> 238536 INFO  (qtp422462028-2292) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&shards.purpose=64&cat_s=cat_s&shard.url=127.0.0.1:35318/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={r1+:+{+type:range,+field:num_d+start:-20,+end:20,+gap:40+++,+facet:{cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}}}&NOW=1518664718252&ids=22,23,21&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238536 INFO  (qtp199107231-2264) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&shards.purpose=64&cat_s=cat_s&shard.url=127.0.0.1:45284/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={r1+:+{+type:range,+field:num_d+start:-20,+end:20,+gap:40+++,+facet:{cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}}}&NOW=1518664718252&ids=01,02&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238536 INFO  (qtp560764968-2280) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={"refine":{"r1":{"_s":[[-20.0,{"cat0":{"_l":["A"]}}]]}}}&shards.purpose=2097216&cat_s=cat_s&shard.url=127.0.0.1:42251/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={r1+:+{+type:range,+field:num_d+start:-20,+end:20,+gap:40+++,+facet:{cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}}}&NOW=1518664718252&ids=11,12,13&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238537 INFO  (qtp560764968-2275) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&shards=127.0.0.1:45284/solr/collection1,127.0.0.1:42251/solr/collection1,127.0.0.1:35318/solr/collection1&shards=debugQuery&shards=true&q=*:*&json.facet={r1+:+{+type:range,+field:${num_d}+start:-20,+end:20,+gap:40+++,+facet:{cat0:{${terms}+type:terms,+field:${cat_s},+sort:'count+desc',+limit:1,+overrequest:0,+refine:true}}}}&indent=true&cat_s=cat_s&xy_s=xy_s&wt=json&version=2.2&er_s=er_s&num_d=num_d&qw_s=qw_s}
 hits=8 status=0 QTime=6
   [junit4]   2> 238552 INFO  (qtp422462028-2292) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:35318/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true,+facet:{+stat1:'sum(num_d)'}+++}}&NOW=1518664718261&isShard=true&wt=javabin}
 hits=3 status=0 QTime=4
   [junit4]   2> 238553 INFO  (qtp560764968-2278) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:42251/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true,+facet:{+stat1:'sum(num_d)'}+++}}&NOW=1518664718261&isShard=true&wt=javabin}
 hits=3 status=0 QTime=5
   [junit4]   2> 238553 INFO  (qtp199107231-2265) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:45284/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true,+facet:{+stat1:'sum(num_d)'}+++}}&NOW=1518664718261&isShard=true&wt=javabin}
 hits=2 status=0 QTime=5
   [junit4]   2> 238554 INFO  (qtp199107231-2264) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&shards.purpose=64&cat_s=cat_s&shard.url=127.0.0.1:45284/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true,+facet:{+stat1:'sum(num_d)'}+++}}&NOW=1518664718261&ids=01,02&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238554 INFO  (qtp560764968-2280) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={"refine":{"cat0":{"_l":["A"]}}}&shards.purpose=2097216&cat_s=cat_s&shard.url=127.0.0.1:42251/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true,+facet:{+stat1:'sum(num_d)'}+++}}&NOW=1518664718261&ids=11,12,13&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238554 INFO  (qtp422462028-2292) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&shards.purpose=64&cat_s=cat_s&shard.url=127.0.0.1:35318/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={cat0:{+type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0,+refine:true,+facet:{+stat1:'sum(num_d)'}+++}}&NOW=1518664718261&ids=22,23,21&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238554 INFO  (qtp422462028-2294) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&shards=127.0.0.1:45284/solr/collection1,127.0.0.1:42251/solr/collection1,127.0.0.1:35318/solr/collection1&shards=debugQuery&shards=true&q=*:*&json.facet={cat0:{${terms}+type:terms,+field:${cat_s},+sort:'count+desc',+limit:1,+overrequest:0,+refine:true,+facet:{+stat1:'sum(${num_d})'}+++}}&indent=true&cat_s=cat_s&xy_s=xy_s&wt=json&version=2.2&er_s=er_s&num_d=num_d&qw_s=qw_s}
 hits=8 status=0 QTime=15
   [junit4]   2> 238593 INFO  (qtp422462028-2292) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:35318/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={+cat0:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:false,+facet:{+min1:'min(num_d)'}+++},cat1:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:true,++facet:{+min1:'min(num_d)'}+++},qfacet:{type:query,+q:'*:*',+facet:{++cat2:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:true,++facet:{+min1:'min(num_d)'}+++}++}},allf:{+type:terms,+field:all_s,++facet:{++cat3:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:true,++facet:{+min1:'min(num_d)'}+++}++}},sum1:'sum(num_d)'}&NOW=1518664718277&isShard=true&wt=javabin}
 hits=3 status=0 QTime=2
   [junit4]   2> 238594 INFO  (qtp560764968-2278) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:42251/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={+cat0:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:false,+facet:{+min1:'min(num_d)'}+++},cat1:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:true,++facet:{+min1:'min(num_d)'}+++},qfacet:{type:query,+q:'*:*',+facet:{++cat2:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:true,++facet:{+min1:'min(num_d)'}+++}++}},allf:{+type:terms,+field:all_s,++facet:{++cat3:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:true,++facet:{+min1:'min(num_d)'}+++}++}},sum1:'sum(num_d)'}&NOW=1518664718277&isShard=true&wt=javabin}
 hits=3 status=0 QTime=0
   [junit4]   2> 238594 INFO  (qtp199107231-2265) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:45284/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={+cat0:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:false,+facet:{+min1:'min(num_d)'}+++},cat1:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:true,++facet:{+min1:'min(num_d)'}+++},qfacet:{type:query,+q:'*:*',+facet:{++cat2:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:true,++facet:{+min1:'min(num_d)'}+++}++}},allf:{+type:terms,+field:all_s,++facet:{++cat3:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:true,++facet:{+min1:'min(num_d)'}+++}++}},sum1:'sum(num_d)'}&NOW=1518664718277&isShard=true&wt=javabin}
 hits=2 status=0 QTime=0
   [junit4]   2> 238596 INFO  (qtp199107231-2264) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&shards.purpose=64&cat_s=cat_s&shard.url=127.0.0.1:45284/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={+cat0:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:false,+facet:{+min1:'min(num_d)'}+++},cat1:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:true,++facet:{+min1:'min(num_d)'}+++},qfacet:{type:query,+q:'*:*',+facet:{++cat2:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:true,++facet:{+min1:'min(num_d)'}+++}++}},allf:{+type:terms,+field:all_s,++facet:{++cat3:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:true,++facet:{+min1:'min(num_d)'}+++}++}},sum1:'sum(num_d)'}&NOW=1518664718277&ids=01,02&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238597 INFO  (qtp422462028-2294) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&shards.purpose=64&cat_s=cat_s&shard.url=127.0.0.1:35318/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={+cat0:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:false,+facet:{+min1:'min(num_d)'}+++},cat1:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:true,++facet:{+min1:'min(num_d)'}+++},qfacet:{type:query,+q:'*:*',+facet:{++cat2:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:true,++facet:{+min1:'min(num_d)'}+++}++}},allf:{+type:terms,+field:all_s,++facet:{++cat3:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:true,++facet:{+min1:'min(num_d)'}+++}++}},sum1:'sum(num_d)'}&NOW=1518664718277&ids=22,23,21&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238600 INFO  (qtp560764968-2280) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={"refine":{"qfacet":{"cat2":{"_l":["A"]}},"allf":{"_s":[["all",{"cat3":{"_l":["A"]}}]]},"cat1":{"_l":["A"]}}}&shards.purpose=2097216&cat_s=cat_s&shard.url=127.0.0.1:42251/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={+cat0:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:false,+facet:{+min1:'min(num_d)'}+++},cat1:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:true,++facet:{+min1:'min(num_d)'}+++},qfacet:{type:query,+q:'*:*',+facet:{++cat2:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:true,++facet:{+min1:'min(num_d)'}+++}++}},allf:{+type:terms,+field:all_s,++facet:{++cat3:{+type:terms,+field:cat_s,+sort:'min1+asc',+limit:1,+overrequest:0,+refine:true,++facet:{+min1:'min(num_d)'}+++}++}},sum1:'sum(num_d)'}&NOW=1518664718277&ids=11,12,13&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238600 INFO  (qtp422462028-2297) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&shards=127.0.0.1:45284/solr/collection1,127.0.0.1:42251/solr/collection1,127.0.0.1:35318/solr/collection1&shards=debugQuery&shards=true&q=*:*&json.facet={+cat0:{${terms}+type:terms,+field:${cat_s},+sort:'min1+asc',+limit:1,+overrequest:0,+refine:false,+facet:{+min1:'min(${num_d})'}+++},cat1:{${terms}+type:terms,+field:${cat_s},+sort:'min1+asc',+limit:1,+overrequest:0,+refine:true,++facet:{+min1:'min(${num_d})'}+++},qfacet:{type:query,+q:'*:*',+facet:{++cat2:{${terms}+type:terms,+field:${cat_s},+sort:'min1+asc',+limit:1,+overrequest:0,+refine:true,++facet:{+min1:'min(${num_d})'}+++}++}},allf:{${terms}+type:terms,+field:all_s,++facet:{++cat3:{${terms}+type:terms,+field:${cat_s},+sort:'min1+asc',+limit:1,+overrequest:0,+refine:true,++facet:{+min1:'min(${num_d})'}+++}++}},sum1:'sum(${num_d})'}&indent=true&cat_s=cat_s&xy_s=xy_s&wt=json&version=2.2&er_s=er_s&num_d=num_d&qw_s=qw_s}
 hits=8 status=0 QTime=44
   [junit4]   2> 238602 INFO  (qtp199107231-2266) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:45284/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={ab:{+type:terms,+field:cat_s,+limit:1,+overrequest:0,+refine:true,++facet:{++xy:{+type:terms,+field:xy_s,+limit:1,+overrequest:0,+refine:true+++}++}}}&NOW=1518664718323&isShard=true&wt=javabin}
 hits=2 status=0 QTime=0
   [junit4]   2> 238602 INFO  (qtp560764968-2281) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:42251/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={ab:{+type:terms,+field:cat_s,+limit:1,+overrequest:0,+refine:true,++facet:{++xy:{+type:terms,+field:xy_s,+limit:1,+overrequest:0,+refine:true+++}++}}}&NOW=1518664718323&isShard=true&wt=javabin}
 hits=3 status=0 QTime=0
   [junit4]   2> 238603 INFO  (qtp422462028-2298) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:35318/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={ab:{+type:terms,+field:cat_s,+limit:1,+overrequest:0,+refine:true,++facet:{++xy:{+type:terms,+field:xy_s,+limit:1,+overrequest:0,+refine:true+++}++}}}&NOW=1518664718323&isShard=true&wt=javabin}
 hits=3 status=0 QTime=0
   [junit4]   2> 238605 INFO  (qtp560764968-2275) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={"refine":{"ab":{"_p":[["A",{"xy":{"_l":["X"]}}]]}}}&shards.purpose=2097216&cat_s=cat_s&shard.url=127.0.0.1:42251/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={ab:{+type:terms,+field:cat_s,+limit:1,+overrequest:0,+refine:true,++facet:{++xy:{+type:terms,+field:xy_s,+limit:1,+overrequest:0,+refine:true+++}++}}}&NOW=1518664718323&ids=11,12,13&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238605 INFO  (qtp199107231-2264) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&shards.purpose=64&cat_s=cat_s&shard.url=127.0.0.1:45284/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={ab:{+type:terms,+field:cat_s,+limit:1,+overrequest:0,+refine:true,++facet:{++xy:{+type:terms,+field:xy_s,+limit:1,+overrequest:0,+refine:true+++}++}}}&NOW=1518664718323&ids=01,02&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238605 INFO  (qtp422462028-2298) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&shards.purpose=64&cat_s=cat_s&shard.url=127.0.0.1:35318/solr/collection1&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={ab:{+type:terms,+field:cat_s,+limit:1,+overrequest:0,+refine:true,++facet:{++xy:{+type:terms,+field:xy_s,+limit:1,+overrequest:0,+refine:true+++}++}}}&NOW=1518664718323&ids=22,23,21&isShard=true&wt=javabin}
 status=0 QTime=0
   [junit4]   2> 238606 INFO  (qtp560764968-2278) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&shards=127.0.0.1:45284/solr/collection1,127.0.0.1:42251/solr/collection1,127.0.0.1:35318/solr/collection1&shards=debugQuery&shards=true&q=*:*&json.facet={ab:{${terms}+type:terms,+field:${cat_s},+limit:1,+overrequest:0,+refine:true,++facet:{++xy:{${terms}+type:terms,+field:${xy_s},+limit:1,+overrequest:0,+refine:true+++}++}}}&indent=true&cat_s=cat_s&xy_s=xy_s&wt=json&version=2.2&er_s=er_s&num_d=num_d&qw_s=qw_s}
 hits=8 status=0 QTime=4
   [junit4]   2> 238608 INFO  (qtp560764968-2280) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:42251/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={+ab+:{+type:terms,+field:cat_s,+limit:1,+overrequest:0,+refine:true,++facet:{++xy:{+type:terms,+field:xy_s,+limit:1,+overrequest:0,+refine:true},+qq:{query:'*:*'},ww:'sum(num_d)'++}},ab2:{+type:terms,+field:cat_s,+limit:1,+overrequest:0,+refine:false,+facet:{++xy:{+type:terms,+field:xy_s,+limit:1,+overrequest:0,+refine:true},+qq:{query:'*:*'},ww:'sum(num_d)'++}},allf+:{+type:terms,+field:all_s,+limit:1,+overrequest:0,+refine:true,++facet:{cat:{+type:terms,+field:cat_s,+limit:1,+overrequest:0,+refine:true},+qq:{query:'*:*'},ww:'sum(num_d)'++}},allf2:{+type:terms,+field:all_s,+limit:1,+overrequest:0,+refine:false,+facet:{cat:{+type:terms,+field:cat_s,+limit:1,+overrequest:0,+refine:true},+qq:{query:'*:*'},ww:'sum(num_d)'++}}}&NOW=1518664718329&isShard=true&wt=javabin}
 hits=3 status=0 QTime=0
   [junit4]   2> 238609 INFO  (qtp199107231-2265) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:45284/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={+ab+:{+type:terms,+field:cat_s,+limit:1,+overrequest:0,+refine:true,++facet:{++xy:{+type:terms,+field:xy_s,+limit:1,+overrequest:0,+refine:true},+qq:{query:'*:*'},ww:'sum(num_d)'++}},ab2:{+type:terms,+field:cat_s,+limit:1,+overrequest:0,+refine:false,+facet:{++xy:{+type:terms,+field:xy_s,+limit:1,+overrequest:0,+refine:true},+qq:{query:'*:*'},ww:'sum(num_d)'++}},allf+:{+type:terms,+field:all_s,+limit:1,+overrequest:0,+refine:true,++facet:{cat:{+type:terms,+field:cat_s,+limit:1,+overrequest:0,+refine:true},+qq:{query:'*:*'},ww:'sum(num_d)'++}},allf2:{+type:terms,+field:all_s,+limit:1,+overrequest:0,+refine:false,+facet:{cat:{+type:terms,+field:cat_s,+limit:1,+overrequest:0,+refine:true},+qq:{query:'*:*'},ww:'sum(num_d)'++}}}&NOW=1518664718329&isShard=true&wt=javabin}
 hits=2 status=0 QTime=0
   [junit4]   2> 238610 INFO  (qtp422462028-2298) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/solr path=/select 
params={cat_i=cat_i&df=text&distrib=false&_facet_={}&fl=id&fl=score&shards.purpose=1048580&start=0&fsv=true&cat_s=cat_s&shard.url=127.0.0.1:35318/solr/collection1&rows=10&xy_s=xy_s&version=2&er_s=er_s&num_d=num_d&qw_s=qw_s&q=*:*&json.facet={+ab+:{+type:terms,+field:cat_s,+limit:1,+overr

[...truncated too long message...]

 instance=844574586
   [junit4]   2> 269290 INFO  
(SUITE-TestJsonFacetRefinement-seed#[8FD30D0F3B7F8B49]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 269290 INFO  
(SUITE-TestJsonFacetRefinement-seed#[8FD30D0F3B7F8B49]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@13c83b92: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@c777cdd
   [junit4]   2> 269290 INFO  
(SUITE-TestJsonFacetRefinement-seed#[8FD30D0F3B7F8B49]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 269290 INFO  
(SUITE-TestJsonFacetRefinement-seed#[8FD30D0F3B7F8B49]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@544740af: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@54fe4707
   [junit4]   2> 269291 INFO  
(SUITE-TestJsonFacetRefinement-seed#[8FD30D0F3B7F8B49]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 269291 INFO  
(SUITE-TestJsonFacetRefinement-seed#[8FD30D0F3B7F8B49]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@44534c04: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@42c6269a
   [junit4]   2> 269291 INFO  (coreCloseExecutor-656-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@3a876f2a
   [junit4]   2> 269291 INFO  (coreCloseExecutor-656-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=981954346
   [junit4]   2> 269291 INFO  (coreCloseExecutor-656-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@6a21cc0f: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@5f78426a
   [junit4]   2> 269294 INFO  
(SUITE-TestJsonFacetRefinement-seed#[8FD30D0F3B7F8B49]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> Feb 15, 2018 3:19:09 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> Feb 15, 2018 3:19:29 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> SEVERE: 1 thread leaked from SUITE scope at 
org.apache.solr.search.facet.TestJsonFacetRefinement: 
   [junit4]   2>    1) Thread[id=2264, name=qtp199107231-2264, 
state=TIMED_WAITING, group=TGRP-TestJsonFacetRefinement]
   [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> Feb 15, 2018 3:19:29 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
   [junit4]   2> INFO: Starting to interrupt leaked threads:
   [junit4]   2>    1) Thread[id=2264, name=qtp199107231-2264, 
state=TIMED_WAITING, group=TGRP-TestJsonFacetRefinement]
   [junit4]   2> Feb 15, 2018 3:19:32 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl tryToInterruptAll
   [junit4]   2> SEVERE: There are still zombie threads that couldn't be 
terminated:
   [junit4]   2>    1) Thread[id=2264, name=qtp199107231-2264, 
state=TIMED_WAITING, group=TGRP-TestJsonFacetRefinement]
   [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): 
{qw_sd=PostingsFormat(name=Direct), 
cat_sd=PostingsFormat(name=LuceneVarGapFixedInterval), 
er_sd=PostingsFormat(name=Memory), cat_s=PostingsFormat(name=Direct), 
xy_s=PostingsFormat(name=LuceneFixedGap), xy_ss=PostingsFormat(name=Direct), 
er_s=PostingsFormat(name=LuceneFixedGap), qw_s=PostingsFormat(name=Direct), 
cat_ss=PostingsFormat(name=LuceneFixedGap), er_ss=PostingsFormat(name=Direct), 
qw_ss=PostingsFormat(name=LuceneFixedGap), id=PostingsFormat(name=Memory), 
all_s=PostingsFormat(name=LuceneFixedGap), xy_sd=PostingsFormat(name=Memory)}, 
docValues:{cat_i=DocValuesFormat(name=Lucene70), 
num_dd=DocValuesFormat(name=Direct), _version_=DocValuesFormat(name=Lucene70), 
qw_sd=DocValuesFormat(name=Memory), cat_sd=DocValuesFormat(name=Lucene70), 
er_sd=DocValuesFormat(name=Lucene70), cat_id=DocValuesFormat(name=Memory), 
xy_sd=DocValuesFormat(name=Lucene70), num_d=DocValuesFormat(name=Lucene70), 
cat_is=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=433, 
maxMBSortInHeap=5.730668728871731, sim=RandomSimilarity(queryNorm=false): {}, 
locale=mk, timezone=Indian/Antananarivo
   [junit4]   2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 
1.8.0_144 (64-bit)/cpus=4,threads=2,free=142546584,total=470810624
   [junit4]   2> NOTE: All tests run in this JVM: [TestObjectReleaseTracker, 
CoreAdminCreateDiscoverTest, CreateCollectionCleanupTest, 
BasicDistributedZkTest, TestManagedSchemaThreadSafety, HdfsRecoveryZkTest, 
TestPhraseSuggestions, TestCloudJSONFacetJoinDomain, TestUseDocValuesAsStored2, 
AssignBackwardCompatibilityTest, HdfsChaosMonkeySafeLeaderTest, 
TestTriggerIntegration, OutputWriterTest, TestJsonFacetRefinement]
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestJsonFacetRefinement -Dtests.seed=8FD30D0F3B7F8B49 
-Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=mk 
-Dtests.timezone=Indian/Antananarivo -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J0 | TestJsonFacetRefinement (suite) <<<
   [junit4]    > Throwable #1: 
com.carrotsearch.randomizedtesting.ThreadLeakError: 1 thread leaked from SUITE 
scope at org.apache.solr.search.facet.TestJsonFacetRefinement: 
   [junit4]    >    1) Thread[id=2264, name=qtp199107231-2264, 
state=TIMED_WAITING, group=TGRP-TestJsonFacetRefinement]
   [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([8FD30D0F3B7F8B49]:0)Throwable #2: 
com.carrotsearch.randomizedtesting.ThreadLeakError: There are still zombie 
threads that couldn't be terminated:
   [junit4]    >    1) Thread[id=2264, name=qtp199107231-2264, 
state=TIMED_WAITING, group=TGRP-TestJsonFacetRefinement]
   [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([8FD30D0F3B7F8B49]:0)
   [junit4] Completed [67/775 (1!)] on J0 in 56.25s, 3 tests, 2 errors <<< 
FAILURES!

[...truncated 50750 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to