Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/330/
Java: 64bit/jdk1.8.0_144 -XX:+UseCompressedOops -XX:+UseSerialGC

3 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.lucene.store.TestMultiMMap

Error Message:
Could not remove the following files (in the order of attempts):    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestMultiMMap_D4B5C86CD2D0FF53-001\tempDir-006:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestMultiMMap_D4B5C86CD2D0FF53-001\tempDir-006
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestMultiMMap_D4B5C86CD2D0FF53-001\testImplementations-006:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestMultiMMap_D4B5C86CD2D0FF53-001\testImplementations-006
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestMultiMMap_D4B5C86CD2D0FF53-001\testSeekZero-007:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestMultiMMap_D4B5C86CD2D0FF53-001\testSeekZero-007
 

Stack Trace:
java.io.IOException: Could not remove the following files (in the order of 
attempts):
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestMultiMMap_D4B5C86CD2D0FF53-001\tempDir-006:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestMultiMMap_D4B5C86CD2D0FF53-001\tempDir-006
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestMultiMMap_D4B5C86CD2D0FF53-001\testImplementations-006:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestMultiMMap_D4B5C86CD2D0FF53-001\testImplementations-006
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestMultiMMap_D4B5C86CD2D0FF53-001\testSeekZero-007:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestMultiMMap_D4B5C86CD2D0FF53-001\testSeekZero-007

        at __randomizedtesting.SeedInfo.seed([D4B5C86CD2D0FF53]:0)
        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
        at 
org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216)
        at 
com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


FAILED:  
junit.framework.TestSuite.org.apache.solr.analytics.value.CastingBooleanValueTest

Error Message:
Could not remove the following files (in the order of attempts):    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analytics\test\J0\temp\solr.analytics.value.CastingBooleanValueTest_CEA754D47C3F832F-001\init-core-data-001:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analytics\test\J0\temp\solr.analytics.value.CastingBooleanValueTest_CEA754D47C3F832F-001\init-core-data-001
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analytics\test\J0\temp\solr.analytics.value.CastingBooleanValueTest_CEA754D47C3F832F-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analytics\test\J0\temp\solr.analytics.value.CastingBooleanValueTest_CEA754D47C3F832F-001
 

Stack Trace:
java.io.IOException: Could not remove the following files (in the order of 
attempts):
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analytics\test\J0\temp\solr.analytics.value.CastingBooleanValueTest_CEA754D47C3F832F-001\init-core-data-001:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analytics\test\J0\temp\solr.analytics.value.CastingBooleanValueTest_CEA754D47C3F832F-001\init-core-data-001
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analytics\test\J0\temp\solr.analytics.value.CastingBooleanValueTest_CEA754D47C3F832F-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analytics\test\J0\temp\solr.analytics.value.CastingBooleanValueTest_CEA754D47C3F832F-001

        at __randomizedtesting.SeedInfo.seed([CEA754D47C3F832F]:0)
        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
        at 
org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216)
        at 
com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


FAILED:  junit.framework.TestSuite.org.apache.solr.search.join.GraphQueryTest

Error Message:
Could not remove the following files (in the order of attempts):    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.join.GraphQueryTest_54ECD0B74A43AACC-001\init-core-data-001\tlog:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.join.GraphQueryTest_54ECD0B74A43AACC-001\init-core-data-001\tlog
    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.join.GraphQueryTest_54ECD0B74A43AACC-001\init-core-data-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.join.GraphQueryTest_54ECD0B74A43AACC-001\init-core-data-001
 

Stack Trace:
java.io.IOException: Could not remove the following files (in the order of 
attempts):
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.join.GraphQueryTest_54ECD0B74A43AACC-001\init-core-data-001\tlog:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.join.GraphQueryTest_54ECD0B74A43AACC-001\init-core-data-001\tlog
   
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.join.GraphQueryTest_54ECD0B74A43AACC-001\init-core-data-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.join.GraphQueryTest_54ECD0B74A43AACC-001\init-core-data-001

        at __randomizedtesting.SeedInfo.seed([54ECD0B74A43AACC]:0)
        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
        at 
org.apache.lucene.util.TestRuleTemporaryFilesCleanup.afterAlways(TestRuleTemporaryFilesCleanup.java:216)
        at 
com.carrotsearch.randomizedtesting.rules.TestRuleAdapter$1.afterAlways(TestRuleAdapter.java:31)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 305 lines...]
   [junit4] Suite: org.apache.lucene.store.TestMultiMMap
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{docid=BlockTreeOrds(blocksize=128), 
junk=PostingsFormat(name=LuceneVarGapFixedInterval)}, docValues:{}, 
maxPointsInLeafNode=1185, maxMBSortInHeap=6.452693052173814, 
sim=RandomSimilarity(queryNorm=false): {}, locale=ar-LY, 
timezone=America/Cayenne
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144 
(64-bit)/cpus=3,threads=1,free=72723456,total=97386496
   [junit4]   2> NOTE: All tests run in this JVM: [TestSynonymQuery, 
TestGeoEncodingUtils, TestConsistentFieldNumbers, TestMergePolicyWrapper, 
TestIntArrayDocIdSet, TestIndexWriterDeleteByQuery, TestDemoParallelLeafReader, 
TestComplexExplanations, TestForceMergeForever, TestTerm, TestCodecs, 
TestPrefixRandom, TestOmitPositions, TestSegmentReader, 
TestBlockPostingsFormat2, TestDoubleValuesSource, TestTimeLimitingCollector, 
TestQueryRescorer, TestCodecUtil, TestIOUtils, TestMultiMMap]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestMultiMMap 
-Dtests.seed=D4B5C86CD2D0FF53 -Dtests.slow=true -Dtests.locale=ar-LY 
-Dtests.timezone=America/Cayenne -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J0 | TestMultiMMap (suite) <<<
   [junit4]    > Throwable #1: java.io.IOException: Could not remove the 
following files (in the order of attempts):
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestMultiMMap_D4B5C86CD2D0FF53-001\tempDir-006:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestMultiMMap_D4B5C86CD2D0FF53-001\tempDir-006
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestMultiMMap_D4B5C86CD2D0FF53-001\testImplementations-006:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestMultiMMap_D4B5C86CD2D0FF53-001\testImplementations-006
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestMultiMMap_D4B5C86CD2D0FF53-001\testSeekZero-007:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\lucene\build\core\test\J0\temp\lucene.store.TestMultiMMap_D4B5C86CD2D0FF53-001\testSeekZero-007
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([D4B5C86CD2D0FF53]:0)
   [junit4]    >        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4] Completed [26/458 (1!)] on J0 in 3.63s, 54 tests, 1 error <<< 
FAILURES!

[...truncated 11425 lines...]
   [junit4] Suite: org.apache.solr.search.join.GraphQueryTest
   [junit4]   2> Creating dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.join.GraphQueryTest_54ECD0B74A43AACC-001\init-core-data-001
   [junit4]   2> 326226 WARN  
(SUITE-GraphQueryTest-seed#[54ECD0B74A43AACC]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3
   [junit4]   2> 326226 INFO  
(SUITE-GraphQueryTest-seed#[54ECD0B74A43AACC]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 326231 INFO  
(SUITE-GraphQueryTest-seed#[54ECD0B74A43AACC]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
   [junit4]   2> 326231 INFO  
(SUITE-GraphQueryTest-seed#[54ECD0B74A43AACC]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 326233 INFO  
(SUITE-GraphQueryTest-seed#[54ECD0B74A43AACC]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 326234 INFO  
(SUITE-GraphQueryTest-seed#[54ECD0B74A43AACC]-worker) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 326287 INFO  
(SUITE-GraphQueryTest-seed#[54ECD0B74A43AACC]-worker) [    ] o.a.s.c.SolrConfig 
Using Lucene MatchVersion: 7.3.0
   [junit4]   2> 326300 INFO  
(SUITE-GraphQueryTest-seed#[54ECD0B74A43AACC]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=example
   [junit4]   2> 326345 INFO  
(SUITE-GraphQueryTest-seed#[54ECD0B74A43AACC]-worker) [    ] 
o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
   [junit4]   2> 326428 INFO  
(SUITE-GraphQueryTest-seed#[54ECD0B74A43AACC]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7bdf8c2a
   [junit4]   2> 326434 INFO  
(SUITE-GraphQueryTest-seed#[54ECD0B74A43AACC]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7bdf8c2a
   [junit4]   2> 326435 INFO  
(SUITE-GraphQueryTest-seed#[54ECD0B74A43AACC]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7bdf8c2a
   [junit4]   2> 326436 INFO  (coreLoadExecutor-825-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 326436 INFO  (coreLoadExecutor-825-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib,
 
/C:/Users/jenkins/workspace/Lucene-Solr-7.x-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 326500 INFO  (coreLoadExecutor-825-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
   [junit4]   2> 326539 INFO  (coreLoadExecutor-825-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example
   [junit4]   2> 326602 INFO  (coreLoadExecutor-825-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid 
field id
   [junit4]   2> 326613 INFO  (coreLoadExecutor-825-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1,
 trusted=true
   [junit4]   2> 326613 INFO  (coreLoadExecutor-825-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7bdf8c2a
   [junit4]   2> 326613 INFO  (coreLoadExecutor-825-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 326614 INFO  (coreLoadExecutor-825-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\core\src\test-files\solr\collection1],
 
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.join.GraphQueryTest_54ECD0B74A43AACC-001\init-core-data-001\]
   [junit4]   2> 326616 INFO  (coreLoadExecutor-825-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=30, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 326727 INFO  (coreLoadExecutor-825-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 326727 INFO  (coreLoadExecutor-825-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 326728 INFO  (coreLoadExecutor-825-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 326728 INFO  (coreLoadExecutor-825-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 326730 INFO  (coreLoadExecutor-825-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=41, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 326730 INFO  (coreLoadExecutor-825-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@18fcc277[collection1] main]
   [junit4]   2> 326731 INFO  (coreLoadExecutor-825-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 326738 INFO  (coreLoadExecutor-825-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 326775 INFO  (coreLoadExecutor-825-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 326775 INFO  
(searcherExecutor-826-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 326775 INFO  (coreLoadExecutor-825-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1586059609357942784
   [junit4]   2> 326778 INFO  
(searcherExecutor-826-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 326778 INFO  
(searcherExecutor-826-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 326778 INFO  
(searcherExecutor-826-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 326778 INFO  
(SUITE-GraphQueryTest-seed#[54ECD0B74A43AACC]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 326778 INFO  
(searcherExecutor-826-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 326778 INFO  
(searcherExecutor-826-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 326779 INFO  
(searcherExecutor-826-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 326780 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testGraph
   [junit4]   2> 326781 INFO  
(searcherExecutor-826-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 326782 INFO  
(searcherExecutor-826-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 326783 INFO  
(searcherExecutor-826-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@18fcc277[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 326785 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_1 (1586059609363185664)]} 0 4
   [junit4]   2> 326791 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_2 (1586059609374720000)]} 0 0
   [junit4]   2> 326791 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609374720001,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 326792 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609374720001
   [junit4]   2> 326803 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@11ca63a8[collection1] main]
   [junit4]   2> 326806 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@11ca63a8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):C2)))}
   [junit4]   2> 326806 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 326807 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 14
   [junit4]   2> 326810 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_3 (1586059609391497216)]} 0 3
   [junit4]   2> 326810 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_4 (1586059609394642944)]} 0 0
   [junit4]   2> 326810 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609394642945,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 326810 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609394642945
   [junit4]   2> 326813 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3b76f6e6[collection1] main]
   [junit4]   2> 326816 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3b76f6e6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):C2)
 Uninverting(_1(7.3.0):C2)))}
   [junit4]   2> 326816 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 326816 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 5
   [junit4]   2> 326819 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_5 (1586059609400934400)]} 0 2
   [junit4]   2> 326819 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_6 (1586059609404080128)]} 0 0
   [junit4]   2> 326820 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_7 (1586059609405128704)]} 0 0
   [junit4]   2> 326821 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_8 (1586059609405128705)]} 0 0
   [junit4]   2> 326821 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_9 (1586059609406177280)]} 0 0
   [junit4]   2> 326821 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609406177281,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 326821 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609406177281
   [junit4]   2> 326841 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@6a9cf90b[collection1] main]
   [junit4]   2> 326843 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6a9cf90b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):C2)
 Uninverting(_1(7.3.0):C2) Uninverting(_2(7.3.0):C5)))}
   [junit4]   2> 326843 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 326843 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 21
   [junit4]   2> 326847 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_1 (1586059609429245952)]} 0 3
   [junit4]   2> 326847 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_2 (1586059609433440256)]} 0 0
   [junit4]   2> 326848 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609434488832,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 326848 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609434488832
   [junit4]   2> 326852 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5b8028f8[collection1] main]
   [junit4]   2> 326855 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5b8028f8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.3.0):C2)
 Uninverting(_2(7.3.0):C5) Uninverting(_3(7.3.0):C2)))}
   [junit4]   2> 326855 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 326856 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 6
   [junit4]   2> 326858 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_10 (1586059609442877440)]} 0 2
   [junit4]   2> 326858 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_11 (1586059609444974592)]} 0 0
   [junit4]   2> 326859 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_12 (1586059609446023168)]} 0 0
   [junit4]   2> 326859 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_13 (1586059609446023169)]} 0 0
   [junit4]   2> 326860 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609447071744,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 326860 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609447071744
   [junit4]   2> 326865 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@618b8839[collection1] main]
   [junit4]   2> 326866 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 326866 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@618b8839[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.3.0):C2)
 Uninverting(_2(7.3.0):C5) Uninverting(_3(7.3.0):C2) 
Uninverting(_4(7.3.0):C4)))}
   [junit4]   2> 326866 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 6
   [junit4]   2> 326893 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_s&edge_id=edge_ss&q={!graph+from%3D${node_id}+to%3D${edge_id}}id:doc_1}
 hits=7 status=0 QTime=26
   [junit4]   2> 326894 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_s&edge_id=edge_ss&q={!graph+from%3D${edge_id}+to%3D${node_id}+maxDepth%3D1}id:doc_1}
 hits=3 status=0 QTime=0
   [junit4]   2> 326895 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_s&edge_id=edge_ss&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dtrue+returnOnlyLeaf%3Dfalse}id:doc_8}
 hits=8 status=0 QTime=0
   [junit4]   2> 326896 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_s&edge_id=edge_ss&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+returnOnlyLeaf%3Dfalse}id:doc_8}
 hits=7 status=0 QTime=0
   [junit4]   2> 326898 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_s&edge_id=edge_ss&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dtrue+returnOnlyLeaf%3Dfalse+traversalFilter%3D'text:foo11'}id:doc_8}
 hits=2 status=0 QTime=0
   [junit4]   2> 326898 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_s&edge_id=edge_ss&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dtrue+returnOnlyLeaf%3Dfalse+maxDepth%3D0}id:doc_8}
 hits=1 status=0 QTime=0
   [junit4]   2> 326899 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_s&edge_id=edge_ss&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dtrue+returnOnlyLeaf%3Dfalse+maxDepth%3D1}id:doc_8}
 hits=3 status=0 QTime=0
   [junit4]   2> 326899 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_s&edge_id=edge_ss&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+returnOnlyLeaf%3Dfalse+maxDepth%3D1}id:doc_8}
 hits=2 status=0 QTime=0
   [junit4]   2> 326900 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_s&edge_id=edge_ss&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+returnOnlyLeaf%3Dtrue+maxDepth%3D2}id:doc_8}
 hits=1 status=0 QTime=0
   [junit4]   2> 326900 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_s&edge_id=edge_ss&q={!graph+from%3D${node_id}+to%3D${edge_id}+maxDepth%3D1}id:doc_1}
 hits=2 status=0 QTime=0
   [junit4]   2> 326901 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_s&edge_id=edge_ss&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+maxDepth%3D1}id:doc_1}
 hits=1 status=0 QTime=0
   [junit4]   2> 326903 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_1 (1586059609490063360)]} 0 1
   [junit4]   2> 326904 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_2 (1586059609492160512)]} 0 0
   [junit4]   2> 326904 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609493209088,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 326904 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609493209088
   [junit4]   2> 326907 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@656b2f3c[collection1] main]
   [junit4]   2> 326907 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 326910 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@656b2f3c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.3.0):C2)
 Uninverting(_2(7.3.0):C5) Uninverting(_4(7.3.0):C4) 
Uninverting(_5(7.3.0):C2)))}
   [junit4]   2> 326911 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 6
   [junit4]   2> 326913 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_3 (1586059609500549120)]} 0 1
   [junit4]   2> 326913 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_4 (1586059609502646272)]} 0 0
   [junit4]   2> 326914 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609503694848,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 326914 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609503694848
   [junit4]   2> 326915 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@55907400[collection1] main]
   [junit4]   2> 326916 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 326918 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@55907400[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.3.0):C5)
 Uninverting(_4(7.3.0):C4) Uninverting(_5(7.3.0):C2) 
Uninverting(_6(7.3.0):C2)))}
   [junit4]   2> 326919 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 4
   [junit4]   2> 326921 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_5 (1586059609508937728)]} 0 1
   [junit4]   2> 326921 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_6 (1586059609511034880)]} 0 0
   [junit4]   2> 326922 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_7 (1586059609512083456)]} 0 0
   [junit4]   2> 326923 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_8 (1586059609512083457)]} 0 0
   [junit4]   2> 326923 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_9 (1586059609513132032)]} 0 0
   [junit4]   2> 326923 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609513132033,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 326924 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609513132033
   [junit4]   2> 326928 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@6caf88eb[collection1] main]
   [junit4]   2> 326930 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 326931 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6caf88eb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.3.0):C4)
 Uninverting(_5(7.3.0):C2) Uninverting(_6(7.3.0):C2) 
Uninverting(_7(7.3.0):C5)))}
   [junit4]   2> 326931 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 7
   [junit4]   2> 326933 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_1 (1586059609521520640)]} 0 1
   [junit4]   2> 326934 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_2 (1586059609524666368)]} 0 0
   [junit4]   2> 326934 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609524666369,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 326934 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609524666369
   [junit4]   2> 326936 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5e8093fe[collection1] main]
   [junit4]   2> 326937 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 326939 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5e8093fe[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.3.0):C4)
 Uninverting(_6(7.3.0):C2) Uninverting(_7(7.3.0):C5) 
Uninverting(_8(7.3.0):C2)))}
   [junit4]   2> 326939 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 5
   [junit4]   2> 326941 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_10 (1586059609530957824)]} 0 1
   [junit4]   2> 326942 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_11 (1586059609533054976)]} 0 0
   [junit4]   2> 326943 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_12 (1586059609534103552)]} 0 0
   [junit4]   2> 326943 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_13 (1586059609534103553)]} 0 0
   [junit4]   2> 326944 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609535152128,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 326944 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609535152128
   [junit4]   2> 326946 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@2dfcaa09[collection1] main]
   [junit4]   2> 326949 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 326950 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2dfcaa09[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(7.3.0):C2)
 Uninverting(_7(7.3.0):C5) Uninverting(_8(7.3.0):C2) 
Uninverting(_9(7.3.0):C4)))}
   [junit4]   2> 326950 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 6
   [junit4]   2> 326952 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ss&edge_id=edge_ss&q={!graph+from%3D${node_id}+to%3D${edge_id}}id:doc_1}
 hits=7 status=0 QTime=0
   [junit4]   2> 326953 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ss&edge_id=edge_ss&q={!graph+from%3D${edge_id}+to%3D${node_id}+maxDepth%3D1}id:doc_1}
 hits=3 status=0 QTime=0
   [junit4]   2> 326954 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ss&edge_id=edge_ss&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dtrue+returnOnlyLeaf%3Dfalse}id:doc_8}
 hits=8 status=0 QTime=0
   [junit4]   2> 326955 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ss&edge_id=edge_ss&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+returnOnlyLeaf%3Dfalse}id:doc_8}
 hits=7 status=0 QTime=0
   [junit4]   2> 326956 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ss&edge_id=edge_ss&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dtrue+returnOnlyLeaf%3Dfalse+traversalFilter%3D'text:foo11'}id:doc_8}
 hits=2 status=0 QTime=0
   [junit4]   2> 326957 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ss&edge_id=edge_ss&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dtrue+returnOnlyLeaf%3Dfalse+maxDepth%3D0}id:doc_8}
 hits=1 status=0 QTime=0
   [junit4]   2> 326957 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ss&edge_id=edge_ss&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dtrue+returnOnlyLeaf%3Dfalse+maxDepth%3D1}id:doc_8}
 hits=3 status=0 QTime=0
   [junit4]   2> 326958 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ss&edge_id=edge_ss&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+returnOnlyLeaf%3Dfalse+maxDepth%3D1}id:doc_8}
 hits=2 status=0 QTime=0
   [junit4]   2> 326958 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ss&edge_id=edge_ss&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+returnOnlyLeaf%3Dtrue+maxDepth%3D2}id:doc_8}
 hits=1 status=0 QTime=0
   [junit4]   2> 326959 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ss&edge_id=edge_ss&q={!graph+from%3D${node_id}+to%3D${edge_id}+maxDepth%3D1}id:doc_1}
 hits=2 status=0 QTime=0
   [junit4]   2> 326959 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ss&edge_id=edge_ss&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+maxDepth%3D1}id:doc_1}
 hits=1 status=0 QTime=0
   [junit4]   2> 326961 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_1 (1586059609550880768)]} 0 1
   [junit4]   2> 326962 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_2 (1586059609554026496)]} 0 0
   [junit4]   2> 326963 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609555075072,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 326963 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609555075072
   [junit4]   2> 326965 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@55a53305[collection1] main]
   [junit4]   2> 326968 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@55a53305[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(7.3.0):C2)
 Uninverting(_7(7.3.0):C5) Uninverting(_9(7.3.0):C4) 
Uninverting(_a(7.3.0):C2)))}
   [junit4]   2> 326968 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 326969 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 6
   [junit4]   2> 326971 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_3 (1586059609561366528)]} 0 1
   [junit4]   2> 326972 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_4 (1586059609564512256)]} 0 0
   [junit4]   2> 326972 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609564512257,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 326972 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609564512257
   [junit4]   2> 326976 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7f487e9f[collection1] main]
   [junit4]   2> 326979 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 326979 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7f487e9f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7(7.3.0):C5)
 Uninverting(_9(7.3.0):C4) Uninverting(_a(7.3.0):C2) 
Uninverting(_b(7.3.0):C2)))}
   [junit4]   2> 326980 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 7
   [junit4]   2> 326982 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_5 (1586059609572900864)]} 0 2
   [junit4]   2> 326983 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_6 (1586059609576046592)]} 0 0
   [junit4]   2> 326984 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_7 (1586059609577095168)]} 0 0
   [junit4]   2> 326984 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_8 (1586059609577095169)]} 0 0
   [junit4]   2> 326985 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_9 (1586059609578143744)]} 0 0
   [junit4]   2> 326985 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609578143745,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 326985 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609578143745
   [junit4]   2> 326988 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@783b53fb[collection1] main]
   [junit4]   2> 327001 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 327003 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@783b53fb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(7.3.0):C4)
 Uninverting(_a(7.3.0):C2) Uninverting(_b(7.3.0):C2) 
Uninverting(_c(7.3.0):C5)))}
   [junit4]   2> 327006 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 21
   [junit4]   2> 327013 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_1 (1586059609602260992)]} 0 5
   [junit4]   2> 327013 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_2 (1586059609607503872)]} 0 0
   [junit4]   2> 327014 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609608552448,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 327014 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609608552448
   [junit4]   2> 327024 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7b0793d9[collection1] main]
   [junit4]   2> 327029 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7b0793d9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(7.3.0):C4)
 Uninverting(_b(7.3.0):C2) Uninverting(_c(7.3.0):C5) 
Uninverting(_d(7.3.0):C2)))}
   [junit4]   2> 327032 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 327034 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 20
   [junit4]   2> 327038 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_10 (1586059609630572544)]} 0 3
   [junit4]   2> 327039 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_11 (1586059609634766848)]} 0 0
   [junit4]   2> 327040 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_12 (1586059609634766849)]} 0 0
   [junit4]   2> 327040 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_13 (1586059609635815424)]} 0 0
   [junit4]   2> 327040 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609635815425,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 327040 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609635815425
   [junit4]   2> 327051 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@28071e82[collection1] main]
   [junit4]   2> 327056 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@28071e82[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(7.3.0):C2)
 Uninverting(_c(7.3.0):C5) Uninverting(_d(7.3.0):C2) 
Uninverting(_e(7.3.0):C4)))}
   [junit4]   2> 327058 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 327059 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 18
   [junit4]   2> 327100 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ip&edge_id=edge_ips&q={!graph+from%3D${node_id}+to%3D${edge_id}}id:doc_1}
 hits=7 status=0 QTime=41
   [junit4]   2> 327102 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ip&edge_id=edge_ips&q={!graph+from%3D${edge_id}+to%3D${node_id}+maxDepth%3D1}id:doc_1}
 hits=3 status=0 QTime=0
   [junit4]   2> 327103 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ip&edge_id=edge_ips&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dtrue+returnOnlyLeaf%3Dfalse}id:doc_8}
 hits=8 status=0 QTime=0
   [junit4]   2> 327104 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ip&edge_id=edge_ips&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+returnOnlyLeaf%3Dfalse}id:doc_8}
 hits=7 status=0 QTime=0
   [junit4]   2> 327105 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ip&edge_id=edge_ips&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dtrue+returnOnlyLeaf%3Dfalse+traversalFilter%3D'text:foo11'}id:doc_8}
 hits=2 status=0 QTime=1
   [junit4]   2> 327105 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ip&edge_id=edge_ips&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dtrue+returnOnlyLeaf%3Dfalse+maxDepth%3D0}id:doc_8}
 hits=1 status=0 QTime=0
   [junit4]   2> 327106 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ip&edge_id=edge_ips&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dtrue+returnOnlyLeaf%3Dfalse+maxDepth%3D1}id:doc_8}
 hits=3 status=0 QTime=0
   [junit4]   2> 327106 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ip&edge_id=edge_ips&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+returnOnlyLeaf%3Dfalse+maxDepth%3D1}id:doc_8}
 hits=2 status=0 QTime=0
   [junit4]   2> 327107 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ip&edge_id=edge_ips&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+returnOnlyLeaf%3Dtrue+maxDepth%3D2}id:doc_8}
 hits=1 status=0 QTime=0
   [junit4]   2> 327107 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ip&edge_id=edge_ips&q={!graph+from%3D${node_id}+to%3D${edge_id}+maxDepth%3D1}id:doc_1}
 hits=2 status=0 QTime=0
   [junit4]   2> 327107 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ip&edge_id=edge_ips&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+maxDepth%3D1}id:doc_1}
 hits=1 status=0 QTime=0
   [junit4]   2> 327121 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_1 (1586059609707118592)]} 0 13
   [junit4]   2> 327122 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_2 (1586059609721798656)]} 0 0
   [junit4]   2> 327123 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609722847232,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 327123 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609722847232
   [junit4]   2> 327136 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@500131fe[collection1] main]
   [junit4]   2> 327142 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@500131fe[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_b(7.3.0):C2)
 Uninverting(_c(7.3.0):C5) Uninverting(_e(7.3.0):C4) 
Uninverting(_f(7.3.0):C2)))}
   [junit4]   2> 327144 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 327145 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 21
   [junit4]   2> 327146 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_3 (1586059609745915904)]} 0 1
   [junit4]   2> 327148 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_4 (1586059609749061632)]} 0 1
   [junit4]   2> 327148 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609749061633,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 327148 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609749061633
   [junit4]   2> 327152 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5865007c[collection1] main]
   [junit4]   2> 327178 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5865007c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_c(7.3.0):C5)
 Uninverting(_e(7.3.0):C4) Uninverting(_f(7.3.0):C2) 
Uninverting(_g(7.3.0):C2)))}
   [junit4]   2> 327180 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 327181 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 32
   [junit4]   2> 327186 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_5 (1586059609784713216)]} 0 4
   [junit4]   2> 327187 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_6 (1586059609788907520)]} 0 0
   [junit4]   2> 327187 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_7 (1586059609789956096)]} 0 0
   [junit4]   2> 327187 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_8 (1586059609789956097)]} 0 0
   [junit4]   2> 327188 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_9 (1586059609791004672)]} 0 0
   [junit4]   2> 327188 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609791004673,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 327188 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609791004673
   [junit4]   2> 327193 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@6de108a3[collection1] main]
   [junit4]   2> 327199 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6de108a3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_e(7.3.0):C4)
 Uninverting(_f(7.3.0):C2) Uninverting(_g(7.3.0):C2) 
Uninverting(_h(7.3.0):C5)))}
   [junit4]   2> 327200 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 327201 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 13
   [junit4]   2> 327205 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_1 (1586059609805684736)]} 0 3
   [junit4]   2> 327207 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_2 (1586059609810927616)]} 0 1
   [junit4]   2> 327208 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609811976192,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 327208 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609811976192
   [junit4]   2> 327216 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@47e21b1b[collection1] main]
   [junit4]   2> 327233 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@47e21b1b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_e(7.3.0):C4)
 Uninverting(_g(7.3.0):C2) Uninverting(_h(7.3.0):C5) 
Uninverting(_i(7.3.0):C2)))}
   [junit4]   2> 327235 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 327239 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 31
   [junit4]   2> 327242 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_10 (1586059609844482048)]} 0 2
   [junit4]   2> 327247 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_11 (1586059609852870656)]} 0 2
   [junit4]   2> 327249 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_12 (1586059609853919232)]} 0 1
   [junit4]   2> 327251 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_13 (1586059609857064960)]} 0 1
   [junit4]   2> 327252 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609858113536,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 327252 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609858113536
   [junit4]   2> 327262 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1b47d904[collection1] main]
   [junit4]   2> 327265 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1b47d904[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_g(7.3.0):C2)
 Uninverting(_h(7.3.0):C5) Uninverting(_i(7.3.0):C2) 
Uninverting(_j(7.3.0):C4)))}
   [junit4]   2> 327266 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 327267 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 14
   [junit4]   2> 327271 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ips&edge_id=edge_ips&q={!graph+from%3D${node_id}+to%3D${edge_id}}id:doc_1}
 hits=7 status=0 QTime=3
   [junit4]   2> 327278 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ips&edge_id=edge_ips&q={!graph+from%3D${edge_id}+to%3D${node_id}+maxDepth%3D1}id:doc_1}
 hits=3 status=0 QTime=6
   [junit4]   2> 327280 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ips&edge_id=edge_ips&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dtrue+returnOnlyLeaf%3Dfalse}id:doc_8}
 hits=8 status=0 QTime=1
   [junit4]   2> 327283 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ips&edge_id=edge_ips&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+returnOnlyLeaf%3Dfalse}id:doc_8}
 hits=7 status=0 QTime=2
   [junit4]   2> 327286 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ips&edge_id=edge_ips&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dtrue+returnOnlyLeaf%3Dfalse+traversalFilter%3D'text:foo11'}id:doc_8}
 hits=2 status=0 QTime=2
   [junit4]   2> 327288 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ips&edge_id=edge_ips&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dtrue+returnOnlyLeaf%3Dfalse+maxDepth%3D0}id:doc_8}
 hits=1 status=0 QTime=1
   [junit4]   2> 327289 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ips&edge_id=edge_ips&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dtrue+returnOnlyLeaf%3Dfalse+maxDepth%3D1}id:doc_8}
 hits=3 status=0 QTime=0
   [junit4]   2> 327290 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ips&edge_id=edge_ips&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+returnOnlyLeaf%3Dfalse+maxDepth%3D1}id:doc_8}
 hits=2 status=0 QTime=0
   [junit4]   2> 327291 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ips&edge_id=edge_ips&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+returnOnlyLeaf%3Dtrue+maxDepth%3D2}id:doc_8}
 hits=1 status=0 QTime=1
   [junit4]   2> 327291 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ips&edge_id=edge_ips&q={!graph+from%3D${node_id}+to%3D${edge_id}+maxDepth%3D1}id:doc_1}
 hits=2 status=0 QTime=0
   [junit4]   2> 327292 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_ips&edge_id=edge_ips&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+maxDepth%3D1}id:doc_1}
 hits=1 status=0 QTime=0
   [junit4]   2> 327320 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_1 (1586059609900056576)]} 0 28
   [junit4]   2> 327321 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_2 (1586059609930465280)]} 0 0
   [junit4]   2> 327322 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609931513856,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 327322 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609931513856
   [junit4]   2> 327358 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@42c54e08[collection1] main]
   [junit4]   2> 327360 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 327360 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@42c54e08[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_g(7.3.0):C2)
 Uninverting(_h(7.3.0):C5) Uninverting(_j(7.3.0):C4) 
Uninverting(_k(7.3.0):C2)))}
   [junit4]   2> 327360 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 38
   [junit4]   2> 327362 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_3 (1586059609972408320)]} 0 1
   [junit4]   2> 327363 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_4 (1586059609973456896)]} 0 0
   [junit4]   2> 327363 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609974505472,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 327363 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609974505472
   [junit4]   2> 327369 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@39422921[collection1] main]
   [junit4]   2> 327371 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@39422921[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_h(7.3.0):C5)
 Uninverting(_j(7.3.0):C4) Uninverting(_k(7.3.0):C2) 
Uninverting(_l(7.3.0):C2)))}
   [junit4]   2> 327372 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 327372 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 8
   [junit4]   2> 327373 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_5 (1586059609983942656)]} 0 1
   [junit4]   2> 327375 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_6 (1586059609986039808)]} 0 0
   [junit4]   2> 327375 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_7 (1586059609987088384)]} 0 0
   [junit4]   2> 327376 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_8 (1586059609988136960)]} 0 0
   [junit4]   2> 327377 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_9 (1586059609989185536)]} 0 0
   [junit4]   2> 327377 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059609989185537,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 327377 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059609989185537
   [junit4]   2> 327381 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@75978736[collection1] main]
   [junit4]   2> 327389 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@75978736[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_j(7.3.0):C4)
 Uninverting(_k(7.3.0):C2) Uninverting(_l(7.3.0):C2) 
Uninverting(_m(7.3.0):C5)))}
   [junit4]   2> 327389 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 327390 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 12
   [junit4]   2> 327391 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_1 (1586059610002817024)]} 0 1
   [junit4]   2> 327393 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_2 (1586059610003865600)]} 0 0
   [junit4]   2> 327393 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059610005962752,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 327393 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059610005962752
   [junit4]   2> 327403 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@df8f59a[collection1] main]
   [junit4]   2> 327406 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@df8f59a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_j(7.3.0):C4)
 Uninverting(_l(7.3.0):C2) Uninverting(_m(7.3.0):C5) 
Uninverting(_n(7.3.0):C2)))}
   [junit4]   2> 327406 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 327411 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 18
   [junit4]   2> 327415 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_10 (1586059610024837120)]} 0 3
   [junit4]   2> 327416 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_11 (1586059610030080000)]} 0 0
   [junit4]   2> 327419 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_12 (1586059610031128576)]} 0 1
   [junit4]   2> 327423 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_13 (1586059610036371456)]} 0 1
   [junit4]   2> 327424 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059610038468608,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 327424 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059610038468608
   [junit4]   2> 327429 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@672912cd[collection1] main]
   [junit4]   2> 327431 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@672912cd[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_l(7.3.0):C2)
 Uninverting(_m(7.3.0):C5) Uninverting(_n(7.3.0):C2) 
Uninverting(_o(7.3.0):C4)))}
   [junit4]   2> 327432 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 327432 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 7
   [junit4]   2> 327438 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_lp&edge_id=edge_lps&q={!graph+from%3D${node_id}+to%3D${edge_id}}id:doc_1}
 hits=7 status=0 QTime=5
   [junit4]   2> 327439 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_lp&edge_id=edge_lps&q={!graph+from%3D${edge_id}+to%3D${node_id}+maxDepth%3D1}id:doc_1}
 hits=3 status=0 QTime=0
   [junit4]   2> 327441 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_lp&edge_id=edge_lps&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dtrue+returnOnlyLeaf%3Dfalse}id:doc_8}
 hits=8 status=0 QTime=1
   [junit4]   2> 327441 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_lp&edge_id=edge_lps&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+returnOnlyLeaf%3Dfalse}id:doc_8}
 hits=7 status=0 QTime=0
   [junit4]   2> 327442 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_lp&edge_id=edge_lps&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dtrue+returnOnlyLeaf%3Dfalse+traversalFilter%3D'text:foo11'}id:doc_8}
 hits=2 status=0 QTime=0
   [junit4]   2> 327442 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_lp&edge_id=edge_lps&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dtrue+returnOnlyLeaf%3Dfalse+maxDepth%3D0}id:doc_8}
 hits=1 status=0 QTime=0
   [junit4]   2> 327443 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_lp&edge_id=edge_lps&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dtrue+returnOnlyLeaf%3Dfalse+maxDepth%3D1}id:doc_8}
 hits=3 status=0 QTime=0
   [junit4]   2> 327443 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_lp&edge_id=edge_lps&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+returnOnlyLeaf%3Dfalse+maxDepth%3D1}id:doc_8}
 hits=2 status=0 QTime=0
   [junit4]   2> 327443 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_lp&edge_id=edge_lps&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+returnOnlyLeaf%3Dtrue+maxDepth%3D2}id:doc_8}
 hits=1 status=0 QTime=0
   [junit4]   2> 327444 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_lp&edge_id=edge_lps&q={!graph+from%3D${node_id}+to%3D${edge_id}+maxDepth%3D1}id:doc_1}
 hits=2 status=0 QTime=0
   [junit4]   2> 327444 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_lp&edge_id=edge_lps&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+maxDepth%3D1}id:doc_1}
 hits=1 status=0 QTime=0
   [junit4]   2> 327447 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_1 (1586059610059440128)]} 0 3
   [junit4]   2> 327449 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_2 (1586059610064683008)]} 0 0
   [junit4]   2> 327451 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1586059610066780160,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 327452 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4c549337 
commitCommandVersion:1586059610066780160
   [junit4]   2> 327465 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4014f6ca[collection1] main]
   [junit4]   2> 327469 INFO  (searcherExecutor-826-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4014f6ca[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_l(7.3.0):C2)
 Uninverting(_m(7.3.0):C5) Uninverting(_o(7.3.0):C4) 
Uninverting(_p(7.3.0):C2)))}
   [junit4]   2> 327476 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 327477 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 24
   [junit4]   2> 327502 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_3 (1586059610094043136)]} 0 24
   [junit4]   2> 327503 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[doc_4 (1586059610120257536)]} 0 0
   [junit4]   2> 327503 INFO  (TEST-GraphQuer

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

nlyLeaf%3Dtrue+maxDepth%3D2}id:doc_8} hits=1 status=0 QTime=0
   [junit4]   2> 328399 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_sdN&edge_id=edge_sdsN&q={!graph+from%3D${node_id}+to%3D${edge_id}+maxDepth%3D1}id:doc_1}
 hits=2 status=0 QTime=0
   [junit4]   2> 328400 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_sdN&edge_id=edge_sdsN&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+maxDepth%3D1}id:doc_1}
 hits=1 status=0 QTime=0
   [junit4]   2> 328400 INFO  
(TEST-GraphQueryTest.testGraph-seed#[54ECD0B74A43AACC]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testGraph
   [junit4]   2> 328402 INFO  
(TEST-GraphQueryTest.testGraphQueryParserValidation-seed#[54ECD0B74A43AACC]) [  
  ] o.a.s.SolrTestCaseJ4 ###Starting testGraphQueryParserValidation
   [junit4]   2> 328403 INFO  
(TEST-GraphQueryTest.testGraphQueryParserValidation-seed#[54ECD0B74A43AACC]) [  
  ] o.a.s.h.RequestHandlerBase Ignoring exception matching .
   [junit4]   2> 328403 INFO  
(TEST-GraphQueryTest.testGraphQueryParserValidation-seed#[54ECD0B74A43AACC]) [  
  ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_nothere&edge_id=edge_ss&message=field+node_nothere+not+defined+in+schema&errorCode=400&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+maxDepth%3D1}id:doc_1}
 status=400 QTime=0
   [junit4]   2> 328403 INFO  
(TEST-GraphQueryTest.testGraphQueryParserValidation-seed#[54ECD0B74A43AACC]) [  
  ] o.a.s.h.RequestHandlerBase Ignoring exception matching .
   [junit4]   2> 328403 INFO  
(TEST-GraphQueryTest.testGraphQueryParserValidation-seed#[54ECD0B74A43AACC]) [  
  ] o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={node_id=node_s&edge_id=edge_notthere&message=field+node_nothere+not+defined+in+schema&errorCode=400&q={!graph+from%3D${node_id}+to%3D${edge_id}+returnRoot%3Dfalse+maxDepth%3D1}id:doc_1}
 status=400 QTime=0
   [junit4]   2> 328403 INFO  
(TEST-GraphQueryTest.testGraphQueryParserValidation-seed#[54ECD0B74A43AACC]) [  
  ] o.a.s.SolrTestCaseJ4 ###Ending testGraphQueryParserValidation
   [junit4]   2> 328403 INFO  
(SUITE-GraphQueryTest-seed#[54ECD0B74A43AACC]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 328403 INFO  
(SUITE-GraphQueryTest-seed#[54ECD0B74A43AACC]-worker) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=680901851
   [junit4]   2> 328403 INFO  
(SUITE-GraphQueryTest-seed#[54ECD0B74A43AACC]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 328403 INFO  
(SUITE-GraphQueryTest-seed#[54ECD0B74A43AACC]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@1d8f5327: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@468063aa
   [junit4]   2> 328406 INFO  
(SUITE-GraphQueryTest-seed#[54ECD0B74A43AACC]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 328406 INFO  
(SUITE-GraphQueryTest-seed#[54ECD0B74A43AACC]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@15812ec7: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@2ad2049a
   [junit4]   2> 328408 INFO  
(SUITE-GraphQueryTest-seed#[54ECD0B74A43AACC]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 328408 INFO  
(SUITE-GraphQueryTest-seed#[54ECD0B74A43AACC]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@693c6c57: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@2eed8518
   [junit4]   2> 328409 INFO  (coreCloseExecutor-831-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1a0b0f29
   [junit4]   2> 328409 INFO  (coreCloseExecutor-831-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=436932393
   [junit4]   2> 328409 INFO  (coreCloseExecutor-831-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@3ec8d620: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@4406b301
   [junit4]   2> 328425 INFO  
(SUITE-GraphQueryTest-seed#[54ECD0B74A43AACC]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> NOTE: test params are: codec=Lucene70, 
sim=RandomSimilarity(queryNorm=false): {}, locale=ko, 
timezone=America/Indiana/Vevay
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144 
(64-bit)/cpus=3,threads=1,free=63678944,total=213143552
   [junit4]   2> NOTE: All tests run in this JVM: [AddBlockUpdateTest, 
DistributedSpellCheckComponentTest, RegexBoostProcessorTest, 
CheckHdfsIndexTest, MigrateRouteKeyTest, TestNumericRangeQuery32, 
TestPrepRecovery, JavabinLoaderTest, TestSegmentSorting, 
WordBreakSolrSpellCheckerTest, TestLeaderElectionZkExpiry, 
TestFilteredDocIdSet, TestCSVResponseWriter, TestSolrCloudWithDelegationTokens, 
TestConfigSets, NotRequiredUniqueKeyTest, ForceLeaderTest, 
TestReplicaProperties, DistributedExpandComponentTest, TestRTimerTree, 
TestReqParamsAPI, TestSchemalessBufferedUpdates, GraphQueryTest]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=GraphQueryTest 
-Dtests.seed=54ECD0B74A43AACC -Dtests.slow=true -Dtests.locale=ko 
-Dtests.timezone=America/Indiana/Vevay -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J0 | GraphQueryTest (suite) <<<
   [junit4]    > Throwable #1: java.io.IOException: Could not remove the 
following files (in the order of attempts):
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.join.GraphQueryTest_54ECD0B74A43AACC-001\init-core-data-001\tlog:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.join.GraphQueryTest_54ECD0B74A43AACC-001\init-core-data-001\tlog
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.join.GraphQueryTest_54ECD0B74A43AACC-001\init-core-data-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J0\temp\solr.search.join.GraphQueryTest_54ECD0B74A43AACC-001\init-core-data-001
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([54ECD0B74A43AACC]:0)
   [junit4]    >        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4] Completed [57/751 (1!)] on J0 in 2.24s, 2 tests, 1 error <<< 
FAILURES!

[...truncated 5285 lines...]
   [junit4] Suite: org.apache.solr.analytics.value.CastingBooleanValueTest
   [junit4]   2> Creating dataDir: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analytics\test\J0\temp\solr.analytics.value.CastingBooleanValueTest_CEA754D47C3F832F-001\init-core-data-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {}, 
docValues:{}, maxPointsInLeafNode=1852, maxMBSortInHeap=7.897532914974783, 
sim=RandomSimilarity(queryNorm=false): {}, locale=lv-LV, 
timezone=Africa/Freetown
   [junit4]   2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_144 
(64-bit)/cpus=3,threads=1,free=26141584,total=97714176
   [junit4]   2> NOTE: All tests run in this JVM: [LegacyAbstractAnalyticsTest, 
LegacyQueryFacetCloudTest, ExpressionFactoryTest, EqualFunctionTest, 
LegacyAbstractAnalyticsFacetTest, NegateFunctionTest, CastingIntValueTest, 
LegacyRangeFacetCloudTest, StringCastFunctionTest, AddFunctionTest, 
ConcatFunctionTest, ReplaceFunctionTest, PowerFunctionTest, 
OverallAnalyticsTest, CastingBooleanValueTest]
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=CastingBooleanValueTest -Dtests.seed=CEA754D47C3F832F 
-Dtests.slow=true -Dtests.locale=lv-LV -Dtests.timezone=Africa/Freetown 
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.00s J0 | CastingBooleanValueTest (suite) <<<
   [junit4]    > Throwable #1: java.io.IOException: Could not remove the 
following files (in the order of attempts):
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analytics\test\J0\temp\solr.analytics.value.CastingBooleanValueTest_CEA754D47C3F832F-001\init-core-data-001:
 java.nio.file.AccessDeniedException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analytics\test\J0\temp\solr.analytics.value.CastingBooleanValueTest_CEA754D47C3F832F-001\init-core-data-001
   [junit4]    >    
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analytics\test\J0\temp\solr.analytics.value.CastingBooleanValueTest_CEA754D47C3F832F-001:
 java.nio.file.DirectoryNotEmptyException: 
C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\contrib\solr-analytics\test\J0\temp\solr.analytics.value.CastingBooleanValueTest_CEA754D47C3F832F-001
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([CEA754D47C3F832F]:0)
   [junit4]    >        at org.apache.lucene.util.IOUtils.rm(IOUtils.java:329)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4] Completed [22/78 (1!)] on J0 in 0.09s, 6 tests, 1 error <<< 
FAILURES!

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

Reply via email to