[JENKINS] Lucene-Solr-5.x-Linux (64bit/jdk1.7.0_80) - Build # 14597 - Failure!

[Policeman Jenkins Server]

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Linux/14597/
Java: 64bit/jdk1.7.0_80 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC

1 tests failed.
FAILED:  org.apache.solr.search.join.TestScoreJoinQPNoScore.testJoin

Error Message:
mismatch: '1'!='5' @ response/docs/[0]/id

Stack Trace:
java.lang.RuntimeException: mismatch: '1'!='5' @ response/docs/[0]/id
        at 
__randomizedtesting.SeedInfo.seed([1E082395A7F0F0F9:233B161FC6A7F76F]:0)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:854)
        at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:801)
        at 
org.apache.solr.search.join.TestScoreJoinQPNoScore.testJoin(TestScoreJoinQPNoScore.java:95)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:606)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1660)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:866)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:902)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:916)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:875)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:777)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:811)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:822)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
        at java.lang.Thread.run(Thread.java:745)




Build Log:
[...truncated 9824 lines...]
   [junit4] Suite: org.apache.solr.search.join.TestScoreJoinQPNoScore
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.join.TestScoreJoinQPNoScore_1E082395A7F0F0F9-001/init-core-data-001
   [junit4]   2> 111805 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true)
   [junit4]   2> 111806 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 111806 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 111806 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 111806 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.c.SolrResourceLoader using system property solr.solr.home: 
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr
   [junit4]   2> 111806 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 111806 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 111821 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 111827 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.4.0
   [junit4]   2> 111840 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig-basic.xml
   [junit4]   2> 111840 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.s.IndexSchema Reading Solr Schema from 
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema-docValuesJoin.xml
   [junit4]   2> 111843 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=doc-values-for-Join
   [junit4]   2> 111853 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.s.IndexSchema default search field in schema is id
   [junit4]   2> 111854 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 111856 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 111856 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.c.SolrResourceLoader using system property solr.solr.home: 
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr
   [junit4]   2> 111856 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/'
   [junit4]   2> 111856 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 111856 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.c.SolrResourceLoader using system property solr.solr.home: 
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr
   [junit4]   2> 111856 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.c.CoreContainer New CoreContainer 175149711
   [junit4]   2> 111856 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.c.CoreContainer Loading cores into CoreContainer 
[instanceDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/]
   [junit4]   2> 111856 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.c.CoreContainer loading shared library: 
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/lib
   [junit4]   2> 111856 WARN  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to 
classloader: lib (resolved as: 
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/lib).
   [junit4]   2> 111864 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 
600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 
5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 111865 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 111865 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 111865 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.l.LogWatcher Registering Log Listener [Log4j 
(org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 111865 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for 
authorization module.
   [junit4]   2> 111865 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 111866 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.c.SolrCore Created CoreDescriptor: 
{configSetProperties=configsetprops.json, name=collection1, 
schema=schema-docValuesJoin.xml, loadOnStartup=true, 
absoluteInstDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/,
 instanceDir=collection1, 
dataDir=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.join.TestScoreJoinQPNoScore_1E082395A7F0F0F9-001/init-core-data-001,
 collection=collection1, config=solrconfig-basic.xml, shard=shard1, 
transient=false}
   [junit4]   2> 111866 INFO  (coreLoadExecutor-460-thread-1) [    ] 
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 
'/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/'
   [junit4]   2> 111867 INFO  (coreLoadExecutor-460-thread-1) [    ] 
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 111867 INFO  (coreLoadExecutor-460-thread-1) [    ] 
o.a.s.c.SolrResourceLoader using system property solr.solr.home: 
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr
   [junit4]   2> 111867 INFO  (coreLoadExecutor-460-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes/'
 to classloader
   [junit4]   2> 111867 INFO  (coreLoadExecutor-460-thread-1) [    ] 
o.a.s.c.SolrResourceLoader Adding 
'file:/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/lib/README'
 to classloader
   [junit4]   2> 111878 INFO  (coreLoadExecutor-460-thread-1) [    ] 
o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 111892 INFO  (coreLoadExecutor-460-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.4.0
   [junit4]   2> 111915 INFO  (coreLoadExecutor-460-thread-1) [    ] 
o.a.s.c.Config Loaded SolrConfig: solrconfig-basic.xml
   [junit4]   2> 111916 INFO  (coreLoadExecutor-460-thread-1) [    ] 
o.a.s.s.IndexSchema Reading Solr Schema from 
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf/schema-docValuesJoin.xml
   [junit4]   2> 111920 INFO  (coreLoadExecutor-460-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=doc-values-for-Join
   [junit4]   2> 111938 INFO  (coreLoadExecutor-460-thread-1) [    ] 
o.a.s.s.IndexSchema default search field in schema is id
   [junit4]   2> 111939 INFO  (coreLoadExecutor-460-thread-1) [    ] 
o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 111942 INFO  (coreLoadExecutor-460-thread-1) [    ] 
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default 
properties: Can't find resource 'configsetprops.json' in classpath or 
'/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf'
   [junit4]   2> 111942 INFO  (coreLoadExecutor-460-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
instancedir 
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 111942 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 111942 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/],
 dataDir=[null]
   [junit4]   2> 111942 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: 
collection1
   [junit4]   2> 111943 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.join.TestScoreJoinQPNoScore_1E082395A7F0F0F9-001/init-core-data-001
   [junit4]   2> 111943 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null 
new=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.join.TestScoreJoinQPNoScore_1E082395A7F0F0F9-001/init-core-data-001/index/
   [junit4]   2> 111943 WARN  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 
'/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.join.TestScoreJoinQPNoScore_1E082395A7F0F0F9-001/init-core-data-001/index'
 doesn't exist. Creating new index...
   [junit4]   2> 111943 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for 
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.join.TestScoreJoinQPNoScore_1E082395A7F0F0F9-001/init-core-data-001/index
   [junit4]   2> 111943 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=31, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.8696763033634607]
   [junit4]   2> 111944 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@4957ad 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f1b1f00),segFN=segments_1,generation=1}
   [junit4]   2> 111944 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 111944 INFO  
(OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] 
o.a.s.c.SolrCore Looking for old index directories to cleanup for core 
collection1 in 
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.join.TestScoreJoinQPNoScore_1E082395A7F0F0F9-001/init-core-data-001/
   [junit4]   2> 111946 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as 
default, creating implicit default
   [junit4]   2> 111948 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 111949 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 111950 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 111951 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 111953 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.c.RequestHandlers Registered paths: 
/config,/update,/admin/file,/admin/logging,/update/csv,/admin/segments,/admin/ping,/get,/admin/properties,/update/json/docs,/admin/plugins,/admin/threads,standard,/admin/luke,/update/json,/admin/mbeans,/replication,/schema,/admin/system
   [junit4]   2> 111954 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.c.SolrCore Using default statsCache cache: 
org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 111954 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 111954 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 111954 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=9, maxMergeAtOnceExplicit=2, maxMergedSegmentMB=28.5888671875, 
floorSegmentMB=1.455078125, forceMergeDeletesPctAllowed=2.7519719378845355, 
segmentsPerTier=25.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.11337778140503474
   [junit4]   2> 111954 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@4957ad 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f1b1f00),segFN=segments_1,generation=1}
   [junit4]   2> 111954 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 111955 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@6806cd57[collection1] 
main
   [junit4]   2> 111955 WARN  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 111955 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 111955 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using 
InMemoryStorage
   [junit4]   2> 111955 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 111955 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 111955 INFO  (coreLoadExecutor-460-thread-1) [    
x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 111955 INFO  
(searcherExecutor-461-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6806cd57[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 111956 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 111959 INFO  
(TEST-TestScoreJoinQPNoScore.testJoinQueryType-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testJoinQueryType
   [junit4]   2> 111978 INFO  
(TEST-TestScoreJoinQPNoScore.testJoinQueryType-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testJoinQueryType
   [junit4]   2> 111981 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testRandomJoin
   [junit4]   2> 111981 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.SolrCore [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 111981 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{deleteByQuery=*:*} 0 0
   [junit4]   2> 111987 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[AMNX]} 0 1
   [junit4]   2> 111995 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[LFUP]} 0 8
   [junit4]   2> 111997 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[MZSY]} 0 1
   [junit4]   2> 112001 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[IMEQ]} 0 3
   [junit4]   2> 112002 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[RYZQ]} 0 1
   [junit4]   2> 112005 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[GHIL]} 0 2
   [junit4]   2> 112006 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[TJFR]} 0 1
   [junit4]   2> 112010 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[QXJF]} 0 3
   [junit4]   2> 112011 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[VOHM]} 0 1
   [junit4]   2> 112014 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[JYFS]} 0 2
   [junit4]   2> 112015 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[QBVR]} 0 0
   [junit4]   2> 112015 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 112025 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@4957ad 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f1b1f00),segFN=segments_1,generation=1}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@4957ad 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f1b1f00),segFN=segments_2,generation=2}
   [junit4]   2> 112025 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.SolrCore newest commit generation = 2
   [junit4]   2> 112026 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@6e6e0a03[collection1] main
   [junit4]   2> 112026 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 112026 INFO  (searcherExecutor-461-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6e6e0a03[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.4.0):C2)
 Uninverting(_1(5.4.0):C2) Uninverting(_2(5.4.0):C2) Uninverting(_3(5.4.0):C2) 
Uninverting(_4(5.4.0):C2) Uninverting(_5(5.4.0):C1)))}
   [junit4]   2> 112026 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 11
   [junit4]   2> 112029 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[GVSF]} 0 1
   [junit4]   2> 112032 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[CFNT]} 0 2
   [junit4]   2> 112033 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[KEDZ]} 0 1
   [junit4]   2> 112037 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[KEDZ]} 0 3
   [junit4]   2> 112037 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[ODVZ]} 0 0
   [junit4]   2> 112080 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[LUCI]} 0 42
   [junit4]   2> 112081 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[GWCM]} 0 1
   [junit4]   2> 112085 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[ZUDT]} 0 3
   [junit4]   2> 112085 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 112087 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@4957ad 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f1b1f00),segFN=segments_2,generation=2}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@4957ad 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f1b1f00),segFN=segments_3,generation=3}
   [junit4]   2> 112087 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.SolrCore newest commit generation = 3
   [junit4]   2> 112089 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@650e5678[collection1] main
   [junit4]   2> 112089 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 112089 INFO  (searcherExecutor-461-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@650e5678[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(5.4.0):C16)
 Uninverting(_a(5.4.0):C2)))}
   [junit4]   2> 112090 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 5
   [junit4]   2> 112092 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[IXUV]} 0 1
   [junit4]   2> 112096 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[DLJG]} 0 3
   [junit4]   2> 112097 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 112098 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@4957ad 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f1b1f00),segFN=segments_3,generation=3}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@4957ad 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f1b1f00),segFN=segments_4,generation=4}
   [junit4]   2> 112098 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.SolrCore newest commit generation = 4
   [junit4]   2> 112099 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@319aefb[collection1] main
   [junit4]   2> 112099 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 112099 INFO  (searcherExecutor-461-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@319aefb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(5.4.0):C16)
 Uninverting(_a(5.4.0):C2) Uninverting(_b(5.4.0):C2)))}
   [junit4]   2> 112099 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 2
   [junit4]   2> 112101 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[SEOA]} 0 0
   [junit4]   2> 112104 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[DXFE]} 0 3
   [junit4]   2> 112105 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[GXXL]} 0 0
   [junit4]   2> 112109 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[KOGA]} 0 3
   [junit4]   2> 112110 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[XDWZ]} 0 0
   [junit4]   2> 112112 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[XDWZ]} 0 2
   [junit4]   2> 112114 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[QKMQ]} 0 1
   [junit4]   2> 112114 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 112121 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@4957ad 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f1b1f00),segFN=segments_4,generation=4}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@4957ad 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f1b1f00),segFN=segments_5,generation=5}
   [junit4]   2> 112121 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.SolrCore newest commit generation = 5
   [junit4]   2> 112121 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@37e94aab[collection1] main
   [junit4]   2> 112122 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 112122 INFO  (searcherExecutor-461-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@37e94aab[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(5.4.0):C16)
 Uninverting(_a(5.4.0):C2) Uninverting(_b(5.4.0):C2) Uninverting(_c(5.4.0):C2) 
Uninverting(_d(5.4.0):C2) Uninverting(_e(5.4.0):C2/1:delGen=1) 
Uninverting(_f(5.4.0):C1)))}
   [junit4]   2> 112122 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 8
   [junit4]   2> 112123 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[XTLI]} 0 0
   [junit4]   2> 112126 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[EHFO]} 0 2
   [junit4]   2> 112127 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[PBMP]} 0 0
   [junit4]   2> 112127 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 112161 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@4957ad 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f1b1f00),segFN=segments_5,generation=5}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@4957ad 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f1b1f00),segFN=segments_6,generation=6}
   [junit4]   2> 112161 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.SolrCore newest commit generation = 6
   [junit4]   2> 112165 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@416e3363[collection1] main
   [junit4]   2> 112165 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 112165 INFO  (searcherExecutor-461-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@416e3363[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(5.4.0):C29)))}
   [junit4]   2> 112167 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 39
   [junit4]   2> 112169 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[QMVM]} 0 1
   [junit4]   2> 112171 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[QMVM]} 0 2
   [junit4]   2> 112172 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[HLVW]} 0 0
   [junit4]   2> 112173 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 112177 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@4957ad 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f1b1f00),segFN=segments_6,generation=6}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@4957ad 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f1b1f00),segFN=segments_7,generation=7}
   [junit4]   2> 112177 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.SolrCore newest commit generation = 7
   [junit4]   2> 112177 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@1e9539b0[collection1] main
   [junit4]   2> 112178 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 112178 INFO  (searcherExecutor-461-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1e9539b0[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(5.4.0):C29)
 Uninverting(_j(5.4.0):C2/1:delGen=1) Uninverting(_k(5.4.0):C1)))}
   [junit4]   2> 112178 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 5
   [junit4]   2> 112180 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[UGOG]} 0 1
   [junit4]   2> 112183 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[BFFO]} 0 2
   [junit4]   2> 112184 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[BFFO]} 0 0
   [junit4]   2> 112190 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[PAYK]} 0 6
   [junit4]   2> 112191 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[BBTJ]} 0 1
   [junit4]   2> 112195 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[BAJM]} 0 3
   [junit4]   2> 112196 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[GHXS]} 0 0
   [junit4]   2> 112200 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null 
params={wt=json&indent=true} {add=[VPTO]} 0 3
   [junit4]   2> 112200 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.UpdateHandler start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 112204 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.s.SolrIndexSearcher Opening Searcher@79126c20[collection1] main
   [junit4]   2> 112205 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 112205 INFO  (searcherExecutor-461-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@79126c20[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_i(5.4.0):C29)
 Uninverting(_j(5.4.0):C2/1:delGen=1) Uninverting(_k(5.4.0):C1) 
Uninverting(_l(5.4.0):C2/1) Uninverting(_m(5.4.0):C2) Uninverting(_n(5.4.0):C2) 
Uninverting(_o(5.4.0):C2)))}
   [junit4]   2> 112205 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 5
   [junit4]   2> 112207 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q=*:*&fl=id&sort=_docid_+asc&rows=76&wt=json&indent=true} hits=38 
status=0 QTime=0 
   [junit4]   2> 112213 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_i_dv+to%3Dsmall2_is_dv++++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=1 
   [junit4]   2> 112217 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall_s_dv++++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=13 status=0 QTime=3 
   [junit4]   2> 112219 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall2_ss_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=19 status=0 QTime=1 
   [junit4]   2> 112222 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall3_ss_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=27 status=0 QTime=0 
   [junit4]   2> 112227 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall2_ss_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=24 status=0 QTime=3 
   [junit4]   2> 112229 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_is_dv+to%3Dsmall_i_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112229 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_is_dv+to%3Dsmall2_i_dv++++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112230 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall2_s_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=16 status=0 QTime=0 
   [junit4]   2> 112233 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_i_dv+to%3Dsmall3_is_dv++++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112233 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_i_dv+to%3Dsmall2_is_dv++++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112234 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall_s_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=7 status=0 QTime=0 
   [junit4]   2> 112236 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_is_dv+to%3Dsmall2_i_dv+fromIndex%3Dcollection1+++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112237 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_is_dv+to%3Dsmall2_i_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112239 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall_s_dv++++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=13 status=0 QTime=2 
   [junit4]   2> 112251 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall3_ss_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DNone+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=36 status=0 QTime=9 
   [junit4]   2> 112254 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_is_dv+to%3Dsmall_i_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112254 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_is_dv+to%3Dsmall2_i_dv+fromIndex%3Dcollection1+++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112257 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall2_s_dv++++score%3DNone+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=16 status=0 QTime=1 
   [junit4]   2> 112261 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall_s_dv+fromIndex%3Dcollection1+++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=13 status=0 QTime=2 
   [junit4]   2> 112265 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_ss_dv+to%3Dsmall3_ss_dv+fromIndex%3Dcollection1+++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=30 status=0 QTime=1 
   [junit4]   2> 112268 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall3_ss_dv++++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=27 status=0 QTime=1 
   [junit4]   2> 112273 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall2_s_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=16 status=0 QTime=2 
   [junit4]   2> 112276 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall_s_dv++++score%3DNone+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=7 status=0 QTime=1 
   [junit4]   2> 112278 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_i_dv+to%3Dsmall3_is_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112279 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_is_dv+to%3Dsmall2_i_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112279 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_i_dv+to%3Dsmall2_i_dv+fromIndex%3Dcollection1+++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112280 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall2_s_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=16 status=0 QTime=0 
   [junit4]   2> 112283 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall_s_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=7 status=0 QTime=0 
   [junit4]   2> 112285 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_is_dv+to%3Dsmall_i_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112287 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall2_s_dv+fromIndex%3Dcollection1+++score%3DNone+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=16 status=0 QTime=1 
   [junit4]   2> 112289 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_is_dv+to%3Dsmall2_is_dv++++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112290 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_is_dv+to%3Dsmall3_is_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112291 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall_s_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=7 status=0 QTime=0 
   [junit4]   2> 112293 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_i_dv+to%3Dsmall3_is_dv+fromIndex%3Dcollection1+++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112293 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_is_dv+to%3Dsmall_i_dv+fromIndex%3Dcollection1+++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112294 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_is_dv+to%3Dsmall2_i_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112295 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall3_ss_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=27 status=0 QTime=0 
   [junit4]   2> 112298 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_ss_dv+to%3Dsmall3_ss_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=30 status=0 QTime=1 
   [junit4]   2> 112301 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_i_dv+to%3Dsmall3_is_dv+fromIndex%3Dcollection1+++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112302 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_s_dv+to%3Dsmall2_ss_dv+fromIndex%3Dcollection1+++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=16 status=0 QTime=0 
   [junit4]   2> 112307 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall2_s_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=16 status=0 QTime=2 
   [junit4]   2> 112309 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_is_dv+to%3Dsmall2_is_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112310 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall_s_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=7 status=0 QTime=0 
   [junit4]   2> 112313 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_ss_dv+to%3Dsmall_s_dv++++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=13 status=0 QTime=0 
   [junit4]   2> 112315 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_i_dv+to%3Dsmall2_is_dv++++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112317 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_ss_dv+to%3Dsmall_s_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=13 status=0 QTime=0 
   [junit4]   2> 112319 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_is_dv+to%3Dsmall2_i_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DNone+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112320 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_s_dv+to%3Dsmall2_ss_dv++++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=16 status=0 QTime=0 
   [junit4]   2> 112323 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_i_dv+to%3Dsmall2_is_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112323 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_i_dv+to%3Dsmall2_i_dv++++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112324 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall_s_dv+fromIndex%3Dcollection1+++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=7 status=0 QTime=0 
   [junit4]   2> 112328 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall2_s_dv+fromIndex%3Dcollection1+++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=16 status=0 QTime=2 
   [junit4]   2> 112330 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_i_dv+to%3Dsmall2_is_dv+fromIndex%3Dcollection1+++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112331 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_i_dv+to%3Dsmall3_is_dv+fromIndex%3Dcollection1+++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112332 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall_s_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=7 status=0 QTime=0 
   [junit4]   2> 112334 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_is_dv+to%3Dsmall_i_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DNone+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112334 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_i_dv+to%3Dsmall2_is_dv+fromIndex%3Dcollection1+++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112335 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_i_dv+to%3Dsmall2_i_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112336 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_ss_dv+to%3Dsmall2_ss_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=24 status=0 QTime=1 
   [junit4]   2> 112339 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_i_dv+to%3Dsmall2_i_dv++++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112339 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_is_dv+to%3Dsmall3_is_dv++++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112342 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall_s_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=13 status=0 QTime=2 
   [junit4]   2> 112346 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall_s_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=13 status=0 QTime=2 
   [junit4]   2> 112349 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_is_dv+to%3Dsmall_i_dv+fromIndex%3Dcollection1+++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112350 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_ss_dv+to%3Dsmall_s_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=13 status=0 QTime=0 
   [junit4]   2> 112355 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall3_ss_dv+fromIndex%3Dcollection1+++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=36 status=0 QTime=3 
   [junit4]   2> 112358 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_i_dv+to%3Dsmall_i_dv+fromIndex%3Dcollection1+++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112359 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_i_dv+to%3Dsmall3_is_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112360 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_s_dv+to%3Dsmall2_ss_dv++++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=16 status=0 QTime=0 
   [junit4]   2> 112366 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall3_ss_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=36 status=0 QTime=3 
   [junit4]   2> 112369 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall2_s_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=16 status=0 QTime=0 
   [junit4]   2> 112372 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_s_dv+to%3Dsmall_s_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=13 status=0 QTime=0 
   [junit4]   2> 112375 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_i_dv+to%3Dsmall2_is_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112375 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_s_dv+to%3Dsmall_s_dv++++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=13 status=0 QTime=0 
   [junit4]   2> 112379 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall_s_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DNone+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=7 status=0 QTime=1 
   [junit4]   2> 112383 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall2_ss_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DNone+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=24 status=0 QTime=2 
   [junit4]   2> 112387 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_ss_dv+to%3Dsmall2_ss_dv++++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=24 status=0 QTime=1 
   [junit4]   2> 112389 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_s_dv+to%3Dsmall_s_dv++++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=13 status=0 QTime=0 
   [junit4]   2> 112393 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall_s_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=13 status=0 QTime=2 
   [junit4]   2> 112396 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_s_dv+to%3Dsmall_s_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=13 status=0 QTime=0 
   [junit4]   2> 112399 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_i_dv+to%3Dsmall_i_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DNone+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112399 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_is_dv+to%3Dsmall2_is_dv++++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112401 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall_s_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=13 status=0 QTime=1 
   [junit4]   2> 112405 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall2_s_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=16 status=0 QTime=2 
   [junit4]   2> 112410 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall2_ss_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=24 status=0 QTime=2 
   [junit4]   2> 112414 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall_s_dv+fromIndex%3Dcollection1+++score%3DNone+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=7 status=0 QTime=1 
   [junit4]   2> 112416 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_is_dv+to%3Dsmall_i_dv+fromIndex%3Dcollection1+++score%3DNone+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112418 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall_s_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DNone+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=13 status=0 QTime=1 
   [junit4]   2> 112424 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall3_ss_dv++++score%3DNone+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=36 status=0 QTime=3 
   [junit4]   2> 112426 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_is_dv+to%3Dsmall3_is_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112427 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_s_dv+to%3Dsmall3_ss_dv+fromIndex%3Dcollection1+++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=26 status=0 QTime=0 
   [junit4]   2> 112431 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall2_s_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=16 status=0 QTime=1 
   [junit4]   2> 112435 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_s_dv+to%3Dsmall3_ss_dv++++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=26 status=0 QTime=0 
   [junit4]   2> 112437 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_i_dv+to%3Dsmall_i_dv+fromIndex%3Dcollection1+++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112438 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_is_dv+to%3Dsmall_i_dv++++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112438 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_s_dv+to%3Dsmall_s_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=13 status=0 QTime=0 
   [junit4]   2> 112441 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_is_dv+to%3Dsmall3_is_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DNone+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112441 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_i_dv+to%3Dsmall3_is_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112443 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_ss_dv+to%3Dsmall3_ss_dv+fromIndex%3Dcollection1+++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=30 status=0 QTime=1 
   [junit4]   2> 112445 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_is_dv+to%3Dsmall3_is_dv+fromIndex%3Dcollection1+++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112446 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_s_dv+to%3Dsmall_s_dv+fromIndex%3Dcollection1+++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=13 status=0 QTime=0 
   [junit4]   2> 112449 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_ss_dv+to%3Dsmall2_s_dv+fromIndex%3Dcollection1+++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=16 status=0 QTime=0 
   [junit4]   2> 112453 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_ss_dv+to%3Dsmall_s_dv+fromIndex%3Dcollection1+++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=13 status=0 QTime=1 
   [junit4]   2> 112455 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_ss_dv+to%3Dsmall_s_dv+fromIndex%3Dcollection1+++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=13 status=0 QTime=0 
   [junit4]   2> 112458 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_i_dv+to%3Dsmall2_i_dv+fromIndex%3Dcollection1+++score%3DNone+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112460 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_s_dv+to%3Dsmall2_s_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DNone+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=9 status=0 QTime=0 
   [junit4]   2> 112462 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_i_dv+to%3Dsmall2_is_dv++++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112463 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_ss_dv+to%3Dsmall2_s_dv++++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=16 status=0 QTime=0 
   [junit4]   2> 112467 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall2_s_dv+fromIndex%3Dcollection1+++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=16 status=0 QTime=2 
   [junit4]   2> 112471 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall2_ss_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DNone+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=19 status=0 QTime=1 
   [junit4]   2> 112474 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall3_ss_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=27 status=0 QTime=0 
   [junit4]   2> 112477 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_i_dv+to%3Dsmall2_is_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112477 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_i_dv+to%3Dsmall3_is_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112478 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall_s_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=7 status=0 QTime=0 
   [junit4]   2> 112480 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_is_dv+to%3Dsmall2_i_dv++++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112481 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_s_dv+to%3Dsmall2_s_dv++++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=9 status=0 QTime=0 
   [junit4]   2> 112483 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_i_dv+to%3Dsmall2_i_dv++++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112483 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_i_dv+to%3Dsmall2_i_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112484 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_is_dv+to%3Dsmall2_i_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112485 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_is_dv+to%3Dsmall2_i_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112486 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_ss_dv+to%3Dsmall_s_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=13 status=0 QTime=0 
   [junit4]   2> 112490 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall3_ss_dv+to%3Dsmall2_s_dv++++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=16 status=0 QTime=2 
   [junit4]   2> 112494 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall3_ss_dv++++score%3DNone+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=27 status=0 QTime=1 
   [junit4]   2> 112496 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_is_dv+to%3Dsmall2_i_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DNone+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112497 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall2_s_dv+fromIndex%3Dcollection1+++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=16 status=0 QTime=0 
   [junit4]   2> 112500 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_i_dv+to%3Dsmall2_i_dv++++score%3DNone+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112500 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_is_dv+to%3Dsmall2_i_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=0 status=0 QTime=0 
   [junit4]   2> 112501 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_s_dv+to%3Dsmall3_ss_dv++++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=26 status=0 QTime=0 
   [junit4]   2> 112504 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_s_dv+to%3Dsmall2_s_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=9 status=0 QTime=0 
   [junit4]   2> 112507 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall2_s_dv+fromIndex%3Dcollection1+++score%3DNone+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=16 status=0 QTime=1 
   [junit4]   2> 112510 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall2_ss_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DTotal+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=19 status=0 QTime=0 
   [junit4]   2> 112513 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall_s_dv+to%3Dsmall2_ss_dv+fromIndex%3Dcollection1+TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=16 status=0 QTime=0 
   [junit4]   2> 112516 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall_s_dv++++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=7 status=0 QTime=0 
   [junit4]   2> 112519 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_ss_dv+to%3Dsmall3_ss_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=30 status=0 QTime=0 
   [junit4]   2> 112522 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_ss_dv+to%3Dsmall2_ss_dv+fromIndex%3Dcollection1+++score%3DMin+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=24 status=0 QTime=0 
   [junit4]   2> 112526 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall3_ss_dv+fromIndex%3Dcollection1+++score%3DNone+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=27 status=0 QTime=1 
   [junit4]   2> 112529 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_ss_dv+to%3Dsmall3_ss_dv++TESTenforceSameCoreAsAnotherOne%3Dtrue++score%3DAvg+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=30 status=0 QTime=1 
   [junit4]   2> 112532 INFO  
(TEST-TestScoreJoinQPNoScore.testRandomJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dsmall2_s_dv+to%3Dsmall2_s_dv++++score%3DMax+}*:*&sort=_docid_+asc&echoParams=all&wt=json&indent=true}
 hits=16 status=0 QTime=0 
   [junit4]   2> 112535 INFO  (TEST-TestScoreJoinQPNoScore.testRandomJoi

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

48228f[collection1] main
   [junit4]   2> 131714 INFO  
(TEST-TestScoreJoinQPNoScore.testJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 131714 INFO  (searcherExecutor-461-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5748228f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2f2(5.4.0):C28)))}
   [junit4]   2> 131715 INFO  
(TEST-TestScoreJoinQPNoScore.testJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} 
{commit=} 0 20
   [junit4]   2> 131719 INFO  
(TEST-TestScoreJoinQPNoScore.testJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Ddept_ss+to%3Ddept_id_s+score%3DAvg+}title_s:MTS&fl=id&wt=xml}
 hits=3 status=0 QTime=3 
   [junit4]   2> 131720 INFO  
(TEST-TestScoreJoinQPNoScore.testJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dnoexist_s+to%3Ddept_id_s+score%3DNone+}*:*&fl=id&wt=xml}
 hits=0 status=0 QTime=0 
   [junit4]   2> 131720 INFO  
(TEST-TestScoreJoinQPNoScore.testJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Ddept_ss+to%3Dnoexist_s+score%3DNone+}*:*&fl=id&wt=xml} 
hits=0 status=0 QTime=0 
   [junit4]   2> 131721 INFO  
(TEST-TestScoreJoinQPNoScore.testJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Dtitle_s+to%3Dtitle_s+score%3DTotal+}name_s:dave&fl=id&wt=xml}
 hits=2 status=0 QTime=0 
   [junit4]   2> 131722 INFO  
(TEST-TestScoreJoinQPNoScore.testJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.c.S.Request [collection1] webapp=null path=null 
params={q={!join+from%3Ddept_id_s+to%3Ddept_ss+score%3DTotal+}text_t:develop&fl=id&wt=xml}
 hits=3 status=0 QTime=0 
   [junit4]   2> 131722 ERROR 
(TEST-TestScoreJoinQPNoScore.testJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.SolrTestCaseJ4 query failed JSON validation. error=mismatch: '1'!='5' @ 
response/docs/[0]/id
   [junit4]   2>  expected 
=/response=={'numFound':3,'start':0,'docs':[{'id':'1'},{'id':'4'},{'id':'5'}]}
   [junit4]   2>  response = {
   [junit4]   2>   "responseHeader":{
   [junit4]   2>     "status":0,
   [junit4]   2>     "QTime":0},
   [junit4]   2>   "response":{"numFound":3,"start":0,"docs":[
   [junit4]   2>       {
   [junit4]   2>         "id":"5"},
   [junit4]   2>       {
   [junit4]   2>         "id":"4"},
   [junit4]   2>       {
   [junit4]   2>         "id":"1"}]
   [junit4]   2>   }}
   [junit4]   2> 
   [junit4]   2>  request = 
q={!join+from%3Ddept_id_s+to%3Ddept_ss+score%3DTotal+}text_t:develop&fl=id&wt=xml
   [junit4]   2> 131723 INFO  
(TEST-TestScoreJoinQPNoScore.testJoin-seed#[1E082395A7F0F0F9]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testJoin
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestScoreJoinQPNoScore -Dtests.method=testJoin 
-Dtests.seed=1E082395A7F0F0F9 -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=ru_RU -Dtests.timezone=Africa/Malabo -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.05s J0 | TestScoreJoinQPNoScore.testJoin <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: mismatch: '1'!='5' @ 
response/docs/[0]/id
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([1E082395A7F0F0F9:233B161FC6A7F76F]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:854)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:801)
   [junit4]    >        at 
org.apache.solr.search.join.TestScoreJoinQPNoScore.testJoin(TestScoreJoinQPNoScore.java:95)
   [junit4]    >        at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 131728 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 131728 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=175149711
   [junit4]   2> 131729 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@738ed773
   [junit4]   2> 131729 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    
x:collection1] o.a.s.u.UpdateHandler closing 
DirectUpdateHandler2{commits=615,autocommits=0,soft 
autocommits=0,optimizes=18,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=5286,cumulative_deletesById=0,cumulative_deletesByQuery=150,cumulative_errors=0}
   [junit4]   2> 131729 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    
x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
   [junit4]   2> 131729 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    
x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 
0 - closing IndexWriter
   [junit4]   2> 131729 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    
x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with 
IndexWriterCloser
   [junit4]   2> 131729 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    
x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@4957ad 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f1b1f00),segFN=segments_e4,generation=508}
   [junit4]   2>        commit{dir=MockDirectoryWrapper(RAMDirectory@4957ad 
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4f1b1f00),segFN=segments_e5,generation=509}
   [junit4]   2> 131730 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    
x:collection1] o.a.s.c.SolrCore newest commit generation = 509
   [junit4]   2> 131730 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    
x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
   [junit4]   2> 131730 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 
directories currently being tracked
   [junit4]   2> 131730 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    
x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.join.TestScoreJoinQPNoScore_1E082395A7F0F0F9-001/init-core-data-001
 
[CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.join.TestScoreJoinQPNoScore_1E082395A7F0F0F9-001/init-core-data-001;done=false>>]
   [junit4]   2> 131731 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.join.TestScoreJoinQPNoScore_1E082395A7F0F0F9-001/init-core-data-001
   [junit4]   2> 131731 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    
x:collection1] o.a.s.c.CachingDirectoryFactory looking to close 
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.join.TestScoreJoinQPNoScore_1E082395A7F0F0F9-001/init-core-data-001/index
 
[CachedDir<<refCount=0;path=/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.join.TestScoreJoinQPNoScore_1E082395A7F0F0F9-001/init-core-data-001/index;done=false>>]
   [junit4]   2> 131731 INFO  
(SUITE-TestScoreJoinQPNoScore-seed#[1E082395A7F0F0F9]-worker) [    
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: 
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.join.TestScoreJoinQPNoScore_1E082395A7F0F0F9-001/init-core-data-001/index
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.join.TestScoreJoinQPNoScore_1E082395A7F0F0F9-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene54), 
sim=DefaultSimilarity, locale=ru_RU, timezone=Africa/Malabo
   [junit4]   2> NOTE: Linux 3.19.0-31-generic amd64/Oracle Corporation 
1.7.0_80 (64-bit)/cpus=12,threads=1,free=237725816,total=507641856
   [junit4]   2> NOTE: All tests run in this JVM: [TestFunctionQuery, 
TestRebalanceLeaders, DistributedSuggestComponentTest, TestSolrXml, 
TestCryptoKeys, TestStressUserVersions, TestScoreJoinQPNoScore]
   [junit4] Completed [23/545] on J0 in 19.94s, 3 tests, 1 error <<< FAILURES!

[...truncated 1653 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:785: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:729: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/build.xml:59: The following error 
occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/build.xml:233: The following 
error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/solr/common-build.xml:526: The 
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1452: The 
following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-5.x-Linux/lucene/common-build.xml:1006: 
There were test failures: 545 suites (7 ignored), 2158 tests, 1 error, 77 
ignored (39 assumptions) [seed: 1E082395A7F0F0F9]

Total time: 48 minutes 52 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any



---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org